Aug 13 00:02:37.981831 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Aug 13 00:02:37.981852 kernel: Linux version 6.12.40-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Tue Aug 12 21:51:24 -00 2025 Aug 13 00:02:37.981862 kernel: KASLR enabled Aug 13 00:02:37.981868 kernel: efi: EFI v2.7 by EDK II Aug 13 00:02:37.981874 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb228018 ACPI 2.0=0xdb9b8018 RNG=0xdb9b8a18 MEMRESERVE=0xdb21fd18 Aug 13 00:02:37.981879 kernel: random: crng init done Aug 13 00:02:37.981886 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Aug 13 00:02:37.981892 kernel: secureboot: Secure boot enabled Aug 13 00:02:37.981898 kernel: ACPI: Early table checksum verification disabled Aug 13 00:02:37.981906 kernel: ACPI: RSDP 0x00000000DB9B8018 000024 (v02 BOCHS ) Aug 13 00:02:37.981912 kernel: ACPI: XSDT 0x00000000DB9B8F18 000064 (v01 BOCHS BXPC 00000001 01000013) Aug 13 00:02:37.981918 kernel: ACPI: FACP 0x00000000DB9B8B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981924 kernel: ACPI: DSDT 0x00000000DB904018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981930 kernel: ACPI: APIC 0x00000000DB9B8C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981937 kernel: ACPI: PPTT 0x00000000DB9B8098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981960 kernel: ACPI: GTDT 0x00000000DB9B8818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981966 kernel: ACPI: MCFG 0x00000000DB9B8A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981973 kernel: ACPI: SPCR 0x00000000DB9B8918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981979 kernel: ACPI: DBG2 0x00000000DB9B8998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981985 kernel: ACPI: IORT 0x00000000DB9B8198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:02:37.981992 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Aug 13 00:02:37.981998 kernel: ACPI: Use ACPI SPCR as default console: Yes Aug 13 00:02:37.982004 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Aug 13 00:02:37.982010 kernel: NODE_DATA(0) allocated [mem 0xdc737a00-0xdc73efff] Aug 13 00:02:37.982017 kernel: Zone ranges: Aug 13 00:02:37.982025 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Aug 13 00:02:37.982031 kernel: DMA32 empty Aug 13 00:02:37.982037 kernel: Normal empty Aug 13 00:02:37.982043 kernel: Device empty Aug 13 00:02:37.982049 kernel: Movable zone start for each node Aug 13 00:02:37.982055 kernel: Early memory node ranges Aug 13 00:02:37.982061 kernel: node 0: [mem 0x0000000040000000-0x00000000dbb4ffff] Aug 13 00:02:37.982068 kernel: node 0: [mem 0x00000000dbb50000-0x00000000dbe7ffff] Aug 13 00:02:37.982074 kernel: node 0: [mem 0x00000000dbe80000-0x00000000dbe9ffff] Aug 13 00:02:37.982080 kernel: node 0: [mem 0x00000000dbea0000-0x00000000dbedffff] Aug 13 00:02:37.982086 kernel: node 0: [mem 0x00000000dbee0000-0x00000000dbf1ffff] Aug 13 00:02:37.982092 kernel: node 0: [mem 0x00000000dbf20000-0x00000000dbf6ffff] Aug 13 00:02:37.982100 kernel: node 0: [mem 0x00000000dbf70000-0x00000000dcbfffff] Aug 13 00:02:37.982106 kernel: node 0: [mem 0x00000000dcc00000-0x00000000dcfdffff] Aug 13 00:02:37.982113 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Aug 13 00:02:37.982122 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Aug 13 00:02:37.982128 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Aug 13 00:02:37.982135 kernel: cma: Reserved 16 MiB at 0x00000000d7a00000 on node -1 Aug 13 00:02:37.982142 kernel: psci: probing for conduit method from ACPI. Aug 13 00:02:37.982150 kernel: psci: PSCIv1.1 detected in firmware. Aug 13 00:02:37.982156 kernel: psci: Using standard PSCI v0.2 function IDs Aug 13 00:02:37.982163 kernel: psci: Trusted OS migration not required Aug 13 00:02:37.982170 kernel: psci: SMC Calling Convention v1.1 Aug 13 00:02:37.982176 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Aug 13 00:02:37.982183 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Aug 13 00:02:37.982190 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Aug 13 00:02:37.982196 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Aug 13 00:02:37.982203 kernel: Detected PIPT I-cache on CPU0 Aug 13 00:02:37.982211 kernel: CPU features: detected: GIC system register CPU interface Aug 13 00:02:37.982217 kernel: CPU features: detected: Spectre-v4 Aug 13 00:02:37.982224 kernel: CPU features: detected: Spectre-BHB Aug 13 00:02:37.982230 kernel: CPU features: kernel page table isolation forced ON by KASLR Aug 13 00:02:37.982237 kernel: CPU features: detected: Kernel page table isolation (KPTI) Aug 13 00:02:37.982244 kernel: CPU features: detected: ARM erratum 1418040 Aug 13 00:02:37.982250 kernel: CPU features: detected: SSBS not fully self-synchronizing Aug 13 00:02:37.982257 kernel: alternatives: applying boot alternatives Aug 13 00:02:37.982264 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=ce82f1ef836ba8581e59ce9db4eef4240d287b2b5f9937c28f0cd024f4dc9107 Aug 13 00:02:37.982271 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 13 00:02:37.982278 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 13 00:02:37.982286 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 13 00:02:37.982292 kernel: Fallback order for Node 0: 0 Aug 13 00:02:37.982299 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Aug 13 00:02:37.982306 kernel: Policy zone: DMA Aug 13 00:02:37.982312 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 13 00:02:37.982319 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Aug 13 00:02:37.982325 kernel: software IO TLB: area num 4. Aug 13 00:02:37.982332 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Aug 13 00:02:37.982339 kernel: software IO TLB: mapped [mem 0x00000000db504000-0x00000000db904000] (4MB) Aug 13 00:02:37.982345 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 13 00:02:37.982352 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 13 00:02:37.982359 kernel: rcu: RCU event tracing is enabled. Aug 13 00:02:37.982368 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Aug 13 00:02:37.982374 kernel: Trampoline variant of Tasks RCU enabled. Aug 13 00:02:37.982381 kernel: Tracing variant of Tasks RCU enabled. Aug 13 00:02:37.982388 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 13 00:02:37.982395 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 13 00:02:37.982401 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Aug 13 00:02:37.982408 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Aug 13 00:02:37.982415 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Aug 13 00:02:37.982422 kernel: GICv3: 256 SPIs implemented Aug 13 00:02:37.982428 kernel: GICv3: 0 Extended SPIs implemented Aug 13 00:02:37.982435 kernel: Root IRQ handler: gic_handle_irq Aug 13 00:02:37.982443 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Aug 13 00:02:37.982449 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Aug 13 00:02:37.982456 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Aug 13 00:02:37.982463 kernel: ITS [mem 0x08080000-0x0809ffff] Aug 13 00:02:37.982469 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Aug 13 00:02:37.982476 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Aug 13 00:02:37.982483 kernel: GICv3: using LPI property table @0x0000000040130000 Aug 13 00:02:37.982489 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Aug 13 00:02:37.982496 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 13 00:02:37.982503 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:02:37.982509 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Aug 13 00:02:37.982516 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Aug 13 00:02:37.982524 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Aug 13 00:02:37.982531 kernel: arm-pv: using stolen time PV Aug 13 00:02:37.982538 kernel: Console: colour dummy device 80x25 Aug 13 00:02:37.982545 kernel: ACPI: Core revision 20240827 Aug 13 00:02:37.982552 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Aug 13 00:02:37.982559 kernel: pid_max: default: 32768 minimum: 301 Aug 13 00:02:37.982566 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Aug 13 00:02:37.982572 kernel: landlock: Up and running. Aug 13 00:02:37.982579 kernel: SELinux: Initializing. Aug 13 00:02:37.982587 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 13 00:02:37.982594 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 13 00:02:37.982601 kernel: rcu: Hierarchical SRCU implementation. Aug 13 00:02:37.982608 kernel: rcu: Max phase no-delay instances is 400. Aug 13 00:02:37.982615 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Aug 13 00:02:37.982622 kernel: Remapping and enabling EFI services. Aug 13 00:02:37.982628 kernel: smp: Bringing up secondary CPUs ... Aug 13 00:02:37.982635 kernel: Detected PIPT I-cache on CPU1 Aug 13 00:02:37.982642 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Aug 13 00:02:37.982650 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Aug 13 00:02:37.982662 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:02:37.982669 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Aug 13 00:02:37.982677 kernel: Detected PIPT I-cache on CPU2 Aug 13 00:02:37.982685 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Aug 13 00:02:37.982692 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Aug 13 00:02:37.982699 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:02:37.982706 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Aug 13 00:02:37.982720 kernel: Detected PIPT I-cache on CPU3 Aug 13 00:02:37.982729 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Aug 13 00:02:37.982737 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Aug 13 00:02:37.982744 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:02:37.982751 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Aug 13 00:02:37.982758 kernel: smp: Brought up 1 node, 4 CPUs Aug 13 00:02:37.982765 kernel: SMP: Total of 4 processors activated. Aug 13 00:02:37.982772 kernel: CPU: All CPU(s) started at EL1 Aug 13 00:02:37.982779 kernel: CPU features: detected: 32-bit EL0 Support Aug 13 00:02:37.982786 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Aug 13 00:02:37.982794 kernel: CPU features: detected: Common not Private translations Aug 13 00:02:37.982801 kernel: CPU features: detected: CRC32 instructions Aug 13 00:02:37.982808 kernel: CPU features: detected: Enhanced Virtualization Traps Aug 13 00:02:37.982815 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Aug 13 00:02:37.982822 kernel: CPU features: detected: LSE atomic instructions Aug 13 00:02:37.982830 kernel: CPU features: detected: Privileged Access Never Aug 13 00:02:37.982837 kernel: CPU features: detected: RAS Extension Support Aug 13 00:02:37.982844 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Aug 13 00:02:37.982851 kernel: alternatives: applying system-wide alternatives Aug 13 00:02:37.982860 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Aug 13 00:02:37.982867 kernel: Memory: 2421860K/2572288K available (11136K kernel code, 2436K rwdata, 9080K rodata, 39488K init, 1038K bss, 128092K reserved, 16384K cma-reserved) Aug 13 00:02:37.982874 kernel: devtmpfs: initialized Aug 13 00:02:37.982881 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 13 00:02:37.982888 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 13 00:02:37.982895 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Aug 13 00:02:37.982902 kernel: 0 pages in range for non-PLT usage Aug 13 00:02:37.982909 kernel: 508432 pages in range for PLT usage Aug 13 00:02:37.982916 kernel: pinctrl core: initialized pinctrl subsystem Aug 13 00:02:37.982925 kernel: SMBIOS 3.0.0 present. Aug 13 00:02:37.982932 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Aug 13 00:02:37.982939 kernel: DMI: Memory slots populated: 1/1 Aug 13 00:02:37.982952 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 13 00:02:37.982959 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Aug 13 00:02:37.982967 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Aug 13 00:02:37.982974 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Aug 13 00:02:37.982981 kernel: audit: initializing netlink subsys (disabled) Aug 13 00:02:37.982988 kernel: audit: type=2000 audit(0.045:1): state=initialized audit_enabled=0 res=1 Aug 13 00:02:37.982997 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 13 00:02:37.983004 kernel: cpuidle: using governor menu Aug 13 00:02:37.983012 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Aug 13 00:02:37.983019 kernel: ASID allocator initialised with 32768 entries Aug 13 00:02:37.983026 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 13 00:02:37.983032 kernel: Serial: AMBA PL011 UART driver Aug 13 00:02:37.983040 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 13 00:02:37.983047 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Aug 13 00:02:37.983055 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Aug 13 00:02:37.983062 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Aug 13 00:02:37.983070 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 13 00:02:37.983077 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Aug 13 00:02:37.983083 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Aug 13 00:02:37.983091 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Aug 13 00:02:37.983098 kernel: ACPI: Added _OSI(Module Device) Aug 13 00:02:37.983104 kernel: ACPI: Added _OSI(Processor Device) Aug 13 00:02:37.983111 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 13 00:02:37.983119 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 13 00:02:37.983127 kernel: ACPI: Interpreter enabled Aug 13 00:02:37.983134 kernel: ACPI: Using GIC for interrupt routing Aug 13 00:02:37.983141 kernel: ACPI: MCFG table detected, 1 entries Aug 13 00:02:37.983148 kernel: ACPI: CPU0 has been hot-added Aug 13 00:02:37.983155 kernel: ACPI: CPU1 has been hot-added Aug 13 00:02:37.983162 kernel: ACPI: CPU2 has been hot-added Aug 13 00:02:37.983169 kernel: ACPI: CPU3 has been hot-added Aug 13 00:02:37.983176 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Aug 13 00:02:37.983183 kernel: printk: legacy console [ttyAMA0] enabled Aug 13 00:02:37.983192 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 13 00:02:37.983336 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 13 00:02:37.983406 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Aug 13 00:02:37.983469 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Aug 13 00:02:37.983531 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Aug 13 00:02:37.983592 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Aug 13 00:02:37.983602 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Aug 13 00:02:37.983612 kernel: PCI host bridge to bus 0000:00 Aug 13 00:02:37.983680 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Aug 13 00:02:37.983751 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Aug 13 00:02:37.983810 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Aug 13 00:02:37.983866 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 13 00:02:37.983960 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Aug 13 00:02:37.984040 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Aug 13 00:02:37.984176 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Aug 13 00:02:37.984243 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Aug 13 00:02:37.984308 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Aug 13 00:02:37.984373 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Aug 13 00:02:37.984440 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Aug 13 00:02:37.984541 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Aug 13 00:02:37.984614 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Aug 13 00:02:37.984673 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Aug 13 00:02:37.984746 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Aug 13 00:02:37.984757 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Aug 13 00:02:37.984765 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Aug 13 00:02:37.984772 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Aug 13 00:02:37.984780 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Aug 13 00:02:37.984787 kernel: iommu: Default domain type: Translated Aug 13 00:02:37.984797 kernel: iommu: DMA domain TLB invalidation policy: strict mode Aug 13 00:02:37.984805 kernel: efivars: Registered efivars operations Aug 13 00:02:37.984812 kernel: vgaarb: loaded Aug 13 00:02:37.984819 kernel: clocksource: Switched to clocksource arch_sys_counter Aug 13 00:02:37.984826 kernel: VFS: Disk quotas dquot_6.6.0 Aug 13 00:02:37.984833 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 13 00:02:37.984840 kernel: pnp: PnP ACPI init Aug 13 00:02:37.984915 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Aug 13 00:02:37.984926 kernel: pnp: PnP ACPI: found 1 devices Aug 13 00:02:37.984935 kernel: NET: Registered PF_INET protocol family Aug 13 00:02:37.984957 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 13 00:02:37.984965 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 13 00:02:37.984972 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 13 00:02:37.984980 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 13 00:02:37.984987 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 13 00:02:37.984994 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 13 00:02:37.985002 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 13 00:02:37.985009 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 13 00:02:37.985018 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 13 00:02:37.985025 kernel: PCI: CLS 0 bytes, default 64 Aug 13 00:02:37.985032 kernel: kvm [1]: HYP mode not available Aug 13 00:02:37.985039 kernel: Initialise system trusted keyrings Aug 13 00:02:37.985046 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 13 00:02:37.985053 kernel: Key type asymmetric registered Aug 13 00:02:37.985060 kernel: Asymmetric key parser 'x509' registered Aug 13 00:02:37.985068 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Aug 13 00:02:37.985075 kernel: io scheduler mq-deadline registered Aug 13 00:02:37.985083 kernel: io scheduler kyber registered Aug 13 00:02:37.985091 kernel: io scheduler bfq registered Aug 13 00:02:37.985098 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 13 00:02:37.985105 kernel: ACPI: button: Power Button [PWRB] Aug 13 00:02:37.985113 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Aug 13 00:02:37.985183 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Aug 13 00:02:37.985194 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 13 00:02:37.985201 kernel: thunder_xcv, ver 1.0 Aug 13 00:02:37.985208 kernel: thunder_bgx, ver 1.0 Aug 13 00:02:37.985217 kernel: nicpf, ver 1.0 Aug 13 00:02:37.985224 kernel: nicvf, ver 1.0 Aug 13 00:02:37.985303 kernel: rtc-efi rtc-efi.0: registered as rtc0 Aug 13 00:02:37.985365 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-08-13T00:02:37 UTC (1755043357) Aug 13 00:02:37.985375 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 13 00:02:37.985382 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Aug 13 00:02:37.985390 kernel: watchdog: NMI not fully supported Aug 13 00:02:37.985397 kernel: watchdog: Hard watchdog permanently disabled Aug 13 00:02:37.985405 kernel: NET: Registered PF_INET6 protocol family Aug 13 00:02:37.985413 kernel: Segment Routing with IPv6 Aug 13 00:02:37.985420 kernel: In-situ OAM (IOAM) with IPv6 Aug 13 00:02:37.985427 kernel: NET: Registered PF_PACKET protocol family Aug 13 00:02:37.985434 kernel: Key type dns_resolver registered Aug 13 00:02:37.985441 kernel: registered taskstats version 1 Aug 13 00:02:37.985448 kernel: Loading compiled-in X.509 certificates Aug 13 00:02:37.985455 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.40-flatcar: e74bfacfa68399ed7282bf533dd5901fdb84b882' Aug 13 00:02:37.985462 kernel: Demotion targets for Node 0: null Aug 13 00:02:37.985471 kernel: Key type .fscrypt registered Aug 13 00:02:37.985479 kernel: Key type fscrypt-provisioning registered Aug 13 00:02:37.985486 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 13 00:02:37.985493 kernel: ima: Allocated hash algorithm: sha1 Aug 13 00:02:37.985500 kernel: ima: No architecture policies found Aug 13 00:02:37.985507 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Aug 13 00:02:37.985514 kernel: clk: Disabling unused clocks Aug 13 00:02:37.985521 kernel: PM: genpd: Disabling unused power domains Aug 13 00:02:37.985528 kernel: Warning: unable to open an initial console. Aug 13 00:02:37.985537 kernel: Freeing unused kernel memory: 39488K Aug 13 00:02:37.985544 kernel: Run /init as init process Aug 13 00:02:37.985551 kernel: with arguments: Aug 13 00:02:37.985558 kernel: /init Aug 13 00:02:37.985565 kernel: with environment: Aug 13 00:02:37.985666 kernel: HOME=/ Aug 13 00:02:37.985674 kernel: TERM=linux Aug 13 00:02:37.985681 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 13 00:02:37.985689 systemd[1]: Successfully made /usr/ read-only. Aug 13 00:02:37.985703 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) Aug 13 00:02:37.985720 systemd[1]: Detected virtualization kvm. Aug 13 00:02:37.985728 systemd[1]: Detected architecture arm64. Aug 13 00:02:37.985736 systemd[1]: Running in initrd. Aug 13 00:02:37.985743 systemd[1]: No hostname configured, using default hostname. Aug 13 00:02:37.985751 systemd[1]: Hostname set to . Aug 13 00:02:37.985759 systemd[1]: Initializing machine ID from VM UUID. Aug 13 00:02:37.985768 systemd[1]: Queued start job for default target initrd.target. Aug 13 00:02:37.985776 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:02:37.985784 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:02:37.985792 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 13 00:02:37.985800 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 00:02:37.985808 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 13 00:02:37.985817 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 13 00:02:37.985827 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 13 00:02:37.985836 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 13 00:02:37.985843 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:02:37.985851 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:02:37.985859 systemd[1]: Reached target paths.target - Path Units. Aug 13 00:02:37.985867 systemd[1]: Reached target slices.target - Slice Units. Aug 13 00:02:37.985874 systemd[1]: Reached target swap.target - Swaps. Aug 13 00:02:37.985882 systemd[1]: Reached target timers.target - Timer Units. Aug 13 00:02:37.985891 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 00:02:37.985899 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 00:02:37.985907 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 13 00:02:37.985915 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Aug 13 00:02:37.985922 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:02:37.985930 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 00:02:37.985938 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:02:37.985959 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 00:02:37.985967 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 13 00:02:37.985977 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 00:02:37.985985 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 13 00:02:37.985993 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). Aug 13 00:02:37.986001 systemd[1]: Starting systemd-fsck-usr.service... Aug 13 00:02:37.986008 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 00:02:37.986016 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 00:02:37.986024 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:02:37.986031 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:02:37.986041 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 13 00:02:37.986049 systemd[1]: Finished systemd-fsck-usr.service. Aug 13 00:02:37.986083 systemd-journald[244]: Collecting audit messages is disabled. Aug 13 00:02:37.986105 systemd-journald[244]: Journal started Aug 13 00:02:37.986124 systemd-journald[244]: Runtime Journal (/run/log/journal/aa88a65a03414207884d69c4065da722) is 6M, max 48.5M, 42.4M free. Aug 13 00:02:37.987854 systemd-modules-load[246]: Inserted module 'overlay' Aug 13 00:02:37.992290 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 13 00:02:37.992328 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 00:02:38.012020 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 00:02:38.015975 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:02:38.026970 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 13 00:02:38.030001 kernel: Bridge firewalling registered Aug 13 00:02:38.029967 systemd-modules-load[246]: Inserted module 'br_netfilter' Aug 13 00:02:38.031177 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 00:02:38.033965 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 00:02:38.035459 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 00:02:38.037403 systemd-tmpfiles[266]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Aug 13 00:02:38.045332 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 00:02:38.047403 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 00:02:38.052635 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:02:38.067444 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:02:38.071692 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:02:38.075619 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 00:02:38.078079 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:02:38.080577 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 13 00:02:38.128790 dracut-cmdline[293]: 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=ce82f1ef836ba8581e59ce9db4eef4240d287b2b5f9937c28f0cd024f4dc9107 Aug 13 00:02:38.130596 systemd-resolved[292]: Positive Trust Anchors: Aug 13 00:02:38.130608 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 00:02:38.130640 systemd-resolved[292]: 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 Aug 13 00:02:38.147636 systemd-resolved[292]: Defaulting to hostname 'linux'. Aug 13 00:02:38.149573 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 00:02:38.150837 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:02:38.281018 kernel: SCSI subsystem initialized Aug 13 00:02:38.286976 kernel: Loading iSCSI transport class v2.0-870. Aug 13 00:02:38.296988 kernel: iscsi: registered transport (tcp) Aug 13 00:02:38.314452 kernel: iscsi: registered transport (qla4xxx) Aug 13 00:02:38.314529 kernel: QLogic iSCSI HBA Driver Aug 13 00:02:38.334254 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 13 00:02:38.350934 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 00:02:38.352624 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 13 00:02:38.437881 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 13 00:02:38.440489 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 13 00:02:38.527995 kernel: raid6: neonx8 gen() 18511 MB/s Aug 13 00:02:38.544986 kernel: raid6: neonx4 gen() 15783 MB/s Aug 13 00:02:38.563774 kernel: raid6: neonx2 gen() 13149 MB/s Aug 13 00:02:38.578997 kernel: raid6: neonx1 gen() 10388 MB/s Aug 13 00:02:38.595992 kernel: raid6: int64x8 gen() 6760 MB/s Aug 13 00:02:38.612977 kernel: raid6: int64x4 gen() 7343 MB/s Aug 13 00:02:38.629984 kernel: raid6: int64x2 gen() 6087 MB/s Aug 13 00:02:38.647219 kernel: raid6: int64x1 gen() 4754 MB/s Aug 13 00:02:38.647247 kernel: raid6: using algorithm neonx8 gen() 18511 MB/s Aug 13 00:02:38.665194 kernel: raid6: .... xor() 11715 MB/s, rmw enabled Aug 13 00:02:38.665242 kernel: raid6: using neon recovery algorithm Aug 13 00:02:38.684983 kernel: xor: measuring software checksum speed Aug 13 00:02:38.686312 kernel: 8regs : 18624 MB/sec Aug 13 00:02:38.686342 kernel: 32regs : 20702 MB/sec Aug 13 00:02:38.687064 kernel: arm64_neon : 27955 MB/sec Aug 13 00:02:38.687078 kernel: xor: using function: arm64_neon (27955 MB/sec) Aug 13 00:02:38.751009 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 13 00:02:38.759009 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 13 00:02:38.761763 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:02:38.793276 systemd-udevd[501]: Using default interface naming scheme 'v255'. Aug 13 00:02:38.797513 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:02:38.800056 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 13 00:02:38.833287 dracut-pre-trigger[510]: rd.md=0: removing MD RAID activation Aug 13 00:02:38.857936 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 00:02:38.860528 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 00:02:38.914981 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:02:38.918567 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 13 00:02:38.974979 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Aug 13 00:02:38.984829 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Aug 13 00:02:38.990351 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 13 00:02:38.990402 kernel: GPT:9289727 != 19775487 Aug 13 00:02:38.990413 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 13 00:02:38.991082 kernel: GPT:9289727 != 19775487 Aug 13 00:02:38.991747 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 00:02:38.993860 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 13 00:02:38.993890 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 13 00:02:38.991876 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:02:38.996627 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:02:38.999592 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:02:39.002875 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 13 00:02:39.028590 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 13 00:02:39.030134 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:02:39.043965 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 13 00:02:39.047009 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 13 00:02:39.059283 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 13 00:02:39.060614 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 13 00:02:39.078312 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 13 00:02:39.079666 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 00:02:39.081832 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:02:39.084104 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 00:02:39.087223 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 13 00:02:39.090095 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 13 00:02:39.124675 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 13 00:02:39.205616 disk-uuid[592]: Primary Header is updated. Aug 13 00:02:39.205616 disk-uuid[592]: Secondary Entries is updated. Aug 13 00:02:39.205616 disk-uuid[592]: Secondary Header is updated. Aug 13 00:02:39.210184 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 13 00:02:40.226974 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 13 00:02:40.227144 disk-uuid[600]: The operation has completed successfully. Aug 13 00:02:40.263027 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 13 00:02:40.263135 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 13 00:02:40.306518 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 13 00:02:40.329188 sh[612]: Success Aug 13 00:02:40.353157 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 13 00:02:40.353227 kernel: device-mapper: uevent: version 1.0.3 Aug 13 00:02:40.355211 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Aug 13 00:02:40.369979 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Aug 13 00:02:40.417135 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 13 00:02:40.420665 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 13 00:02:40.444642 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 13 00:02:40.455074 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Aug 13 00:02:40.455175 kernel: BTRFS: device fsid 7658cdd8-2ee4-4f84-82be-1f808605c89c devid 1 transid 42 /dev/mapper/usr (253:0) scanned by mount (624) Aug 13 00:02:40.460149 kernel: BTRFS info (device dm-0): first mount of filesystem 7658cdd8-2ee4-4f84-82be-1f808605c89c Aug 13 00:02:40.460206 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:02:40.460218 kernel: BTRFS info (device dm-0): using free-space-tree Aug 13 00:02:40.469404 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 13 00:02:40.470922 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Aug 13 00:02:40.472470 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 13 00:02:40.473304 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 13 00:02:40.475716 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 13 00:02:40.505999 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (653) Aug 13 00:02:40.508607 kernel: BTRFS info (device vda6): first mount of filesystem cff59a55-3bd9-4c36-9f7f-aabedbf210fb Aug 13 00:02:40.508665 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:02:40.508676 kernel: BTRFS info (device vda6): using free-space-tree Aug 13 00:02:40.520019 kernel: BTRFS info (device vda6): last unmount of filesystem cff59a55-3bd9-4c36-9f7f-aabedbf210fb Aug 13 00:02:40.521672 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 13 00:02:40.524306 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 13 00:02:40.613254 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 00:02:40.616797 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 00:02:40.676609 systemd-networkd[794]: lo: Link UP Aug 13 00:02:40.676621 systemd-networkd[794]: lo: Gained carrier Aug 13 00:02:40.679103 systemd-networkd[794]: Enumeration completed Aug 13 00:02:40.679493 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 00:02:40.680816 systemd[1]: Reached target network.target - Network. Aug 13 00:02:40.681044 systemd-networkd[794]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:02:40.681050 systemd-networkd[794]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 00:02:40.682731 systemd-networkd[794]: eth0: Link UP Aug 13 00:02:40.682897 systemd-networkd[794]: eth0: Gained carrier Aug 13 00:02:40.682910 systemd-networkd[794]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:02:40.719042 systemd-networkd[794]: eth0: DHCPv4 address 10.0.0.143/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 13 00:02:40.756813 ignition[704]: Ignition 2.21.0 Aug 13 00:02:40.756828 ignition[704]: Stage: fetch-offline Aug 13 00:02:40.756871 ignition[704]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:02:40.756880 ignition[704]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:02:40.757136 ignition[704]: parsed url from cmdline: "" Aug 13 00:02:40.757139 ignition[704]: no config URL provided Aug 13 00:02:40.757144 ignition[704]: reading system config file "/usr/lib/ignition/user.ign" Aug 13 00:02:40.757151 ignition[704]: no config at "/usr/lib/ignition/user.ign" Aug 13 00:02:40.757172 ignition[704]: op(1): [started] loading QEMU firmware config module Aug 13 00:02:40.757176 ignition[704]: op(1): executing: "modprobe" "qemu_fw_cfg" Aug 13 00:02:40.775282 ignition[704]: op(1): [finished] loading QEMU firmware config module Aug 13 00:02:40.781599 ignition[704]: parsing config with SHA512: 965d00a5caac7f8affb52b2a26712fe7fd8401ef580635a49416a04368d8c1cec09729f13746d66d2ce358b07340cf31b41709198654cca2a9ce5813fdc9c228 Aug 13 00:02:40.784685 unknown[704]: fetched base config from "system" Aug 13 00:02:40.784698 unknown[704]: fetched user config from "qemu" Aug 13 00:02:40.785008 ignition[704]: fetch-offline: fetch-offline passed Aug 13 00:02:40.785066 ignition[704]: Ignition finished successfully Aug 13 00:02:40.788861 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 00:02:40.790970 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Aug 13 00:02:40.792108 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 13 00:02:40.845157 ignition[812]: Ignition 2.21.0 Aug 13 00:02:40.845173 ignition[812]: Stage: kargs Aug 13 00:02:40.845355 ignition[812]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:02:40.845364 ignition[812]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:02:40.847842 ignition[812]: kargs: kargs passed Aug 13 00:02:40.847917 ignition[812]: Ignition finished successfully Aug 13 00:02:40.851065 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 13 00:02:40.853348 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 13 00:02:40.888093 ignition[820]: Ignition 2.21.0 Aug 13 00:02:40.888109 ignition[820]: Stage: disks Aug 13 00:02:40.888262 ignition[820]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:02:40.888271 ignition[820]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:02:40.890159 ignition[820]: disks: disks passed Aug 13 00:02:40.890223 ignition[820]: Ignition finished successfully Aug 13 00:02:40.892748 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 13 00:02:40.895127 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 13 00:02:40.896880 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 13 00:02:40.899363 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 00:02:40.902310 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 00:02:40.904419 systemd[1]: Reached target basic.target - Basic System. Aug 13 00:02:40.907497 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 13 00:02:40.934530 systemd-fsck[831]: ROOT: clean, 15/553520 files, 52789/553472 blocks Aug 13 00:02:40.944911 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 13 00:02:40.949585 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 13 00:02:41.051977 kernel: EXT4-fs (vda9): mounted filesystem d634334e-91a3-4b77-89ab-775bdd78a572 r/w with ordered data mode. Quota mode: none. Aug 13 00:02:41.052301 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 13 00:02:41.053729 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 13 00:02:41.057277 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 00:02:41.060098 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 13 00:02:41.061204 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 13 00:02:41.061261 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 13 00:02:41.061291 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 00:02:41.072188 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 13 00:02:41.074841 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 13 00:02:41.078965 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (839) Aug 13 00:02:41.081618 kernel: BTRFS info (device vda6): first mount of filesystem cff59a55-3bd9-4c36-9f7f-aabedbf210fb Aug 13 00:02:41.081669 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:02:41.081680 kernel: BTRFS info (device vda6): using free-space-tree Aug 13 00:02:41.086422 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 00:02:41.154963 initrd-setup-root[863]: cut: /sysroot/etc/passwd: No such file or directory Aug 13 00:02:41.163548 initrd-setup-root[870]: cut: /sysroot/etc/group: No such file or directory Aug 13 00:02:41.172413 initrd-setup-root[877]: cut: /sysroot/etc/shadow: No such file or directory Aug 13 00:02:41.179806 initrd-setup-root[884]: cut: /sysroot/etc/gshadow: No such file or directory Aug 13 00:02:41.379000 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 13 00:02:41.381490 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 13 00:02:41.383562 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 13 00:02:41.413149 kernel: BTRFS info (device vda6): last unmount of filesystem cff59a55-3bd9-4c36-9f7f-aabedbf210fb Aug 13 00:02:41.432356 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 13 00:02:41.448108 ignition[953]: INFO : Ignition 2.21.0 Aug 13 00:02:41.448108 ignition[953]: INFO : Stage: mount Aug 13 00:02:41.450591 ignition[953]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:02:41.450591 ignition[953]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:02:41.454196 ignition[953]: INFO : mount: mount passed Aug 13 00:02:41.454196 ignition[953]: INFO : Ignition finished successfully Aug 13 00:02:41.453783 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 13 00:02:41.455311 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 13 00:02:41.457986 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 13 00:02:41.475287 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 00:02:41.514989 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (965) Aug 13 00:02:41.515041 kernel: BTRFS info (device vda6): first mount of filesystem cff59a55-3bd9-4c36-9f7f-aabedbf210fb Aug 13 00:02:41.518253 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:02:41.518309 kernel: BTRFS info (device vda6): using free-space-tree Aug 13 00:02:41.522539 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 00:02:41.558964 ignition[983]: INFO : Ignition 2.21.0 Aug 13 00:02:41.558964 ignition[983]: INFO : Stage: files Aug 13 00:02:41.558964 ignition[983]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:02:41.558964 ignition[983]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:02:41.563191 ignition[983]: DEBUG : files: compiled without relabeling support, skipping Aug 13 00:02:41.563191 ignition[983]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 13 00:02:41.563191 ignition[983]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 13 00:02:41.567045 ignition[983]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 13 00:02:41.567045 ignition[983]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 13 00:02:41.567045 ignition[983]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 13 00:02:41.564831 unknown[983]: wrote ssh authorized keys file for user: core Aug 13 00:02:41.572407 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Aug 13 00:02:41.572407 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Aug 13 00:02:41.576107 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 00:02:41.578201 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 00:02:41.578201 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:02:41.591640 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:02:41.591640 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:02:41.596443 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Aug 13 00:02:41.978753 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Aug 13 00:02:42.364295 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:02:42.364295 ignition[983]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Aug 13 00:02:42.368528 ignition[983]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 13 00:02:42.372521 ignition[983]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 13 00:02:42.372521 ignition[983]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Aug 13 00:02:42.372521 ignition[983]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Aug 13 00:02:42.393511 ignition[983]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Aug 13 00:02:42.398059 ignition[983]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Aug 13 00:02:42.401124 ignition[983]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Aug 13 00:02:42.401124 ignition[983]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 13 00:02:42.401124 ignition[983]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 13 00:02:42.401124 ignition[983]: INFO : files: files passed Aug 13 00:02:42.401124 ignition[983]: INFO : Ignition finished successfully Aug 13 00:02:42.402482 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 13 00:02:42.405606 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 13 00:02:42.407805 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 13 00:02:42.425286 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 13 00:02:42.425387 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 13 00:02:42.430528 initrd-setup-root-after-ignition[1011]: grep: /sysroot/oem/oem-release: No such file or directory Aug 13 00:02:42.435811 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:02:42.435811 initrd-setup-root-after-ignition[1013]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:02:42.439884 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:02:42.440466 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 00:02:42.444523 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 13 00:02:42.446747 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 13 00:02:42.484437 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 13 00:02:42.484540 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 13 00:02:42.487071 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 13 00:02:42.489357 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 13 00:02:42.491877 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 13 00:02:42.492789 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 13 00:02:42.522629 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 00:02:42.525670 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 13 00:02:42.553751 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:02:42.555156 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:02:42.557475 systemd[1]: Stopped target timers.target - Timer Units. Aug 13 00:02:42.565712 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 13 00:02:42.565859 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 00:02:42.575853 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 13 00:02:42.577047 systemd[1]: Stopped target basic.target - Basic System. Aug 13 00:02:42.578958 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 13 00:02:42.580855 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 00:02:42.582785 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 13 00:02:42.584793 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Aug 13 00:02:42.587000 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 13 00:02:42.589404 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 00:02:42.591677 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 13 00:02:42.593630 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 13 00:02:42.595685 systemd[1]: Stopped target swap.target - Swaps. Aug 13 00:02:42.597383 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 13 00:02:42.597522 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 13 00:02:42.600229 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:02:42.602178 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:02:42.604176 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 13 00:02:42.604279 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:02:42.608805 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 13 00:02:42.608950 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 13 00:02:42.611923 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 13 00:02:42.612159 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 00:02:42.614351 systemd[1]: Stopped target paths.target - Path Units. Aug 13 00:02:42.615965 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 13 00:02:42.619012 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:02:42.621829 systemd[1]: Stopped target slices.target - Slice Units. Aug 13 00:02:42.622894 systemd[1]: Stopped target sockets.target - Socket Units. Aug 13 00:02:42.624636 systemd[1]: iscsid.socket: Deactivated successfully. Aug 13 00:02:42.624744 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 00:02:42.626577 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 13 00:02:42.626671 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 00:02:42.628544 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 13 00:02:42.628673 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 00:02:42.630544 systemd[1]: ignition-files.service: Deactivated successfully. Aug 13 00:02:42.630654 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 13 00:02:42.633838 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 13 00:02:42.635711 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 13 00:02:42.635849 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:02:42.659730 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 13 00:02:42.660687 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 13 00:02:42.660857 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:02:42.662817 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 13 00:02:42.662934 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 00:02:42.669087 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 13 00:02:42.669198 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 13 00:02:42.674785 ignition[1037]: INFO : Ignition 2.21.0 Aug 13 00:02:42.674785 ignition[1037]: INFO : Stage: umount Aug 13 00:02:42.676877 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:02:42.676877 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:02:42.676877 ignition[1037]: INFO : umount: umount passed Aug 13 00:02:42.676877 ignition[1037]: INFO : Ignition finished successfully Aug 13 00:02:42.675539 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 13 00:02:42.679734 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 13 00:02:42.679960 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 13 00:02:42.681639 systemd[1]: Stopped target network.target - Network. Aug 13 00:02:42.683055 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 13 00:02:42.683143 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 13 00:02:42.685625 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 13 00:02:42.685711 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 13 00:02:42.687365 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 13 00:02:42.687422 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 13 00:02:42.689094 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 13 00:02:42.689137 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 13 00:02:42.691220 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 13 00:02:42.695998 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 13 00:02:42.703381 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 13 00:02:42.703664 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 13 00:02:42.707339 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Aug 13 00:02:42.707572 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 13 00:02:42.707677 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 13 00:02:42.711022 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Aug 13 00:02:42.711581 systemd[1]: Stopped target network-pre.target - Preparation for Network. Aug 13 00:02:42.713802 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 13 00:02:42.713849 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:02:42.716921 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 13 00:02:42.719338 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 13 00:02:42.719416 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 00:02:42.723953 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 13 00:02:42.724020 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:02:42.728794 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 13 00:02:42.728856 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 13 00:02:42.730476 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 13 00:02:42.730534 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:02:42.738543 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:02:42.742566 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Aug 13 00:02:42.742650 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Aug 13 00:02:42.743023 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 13 00:02:42.743139 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 13 00:02:42.747199 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 13 00:02:42.747296 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 13 00:02:42.758482 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 13 00:02:42.758596 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 13 00:02:42.773671 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 13 00:02:42.774103 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:02:42.776462 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 13 00:02:42.776527 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 13 00:02:42.778754 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 13 00:02:42.778928 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:02:42.780470 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 13 00:02:42.780534 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 13 00:02:42.783302 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 13 00:02:42.783359 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 13 00:02:42.786314 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 13 00:02:42.786370 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:02:42.790326 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 13 00:02:42.792250 systemd[1]: systemd-network-generator.service: Deactivated successfully. Aug 13 00:02:42.792322 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 00:02:42.795774 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 13 00:02:42.795828 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:02:42.799293 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 00:02:42.799341 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:02:42.804147 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Aug 13 00:02:42.804212 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Aug 13 00:02:42.804256 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Aug 13 00:02:42.808012 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 13 00:02:42.808150 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 13 00:02:42.810735 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 13 00:02:42.812820 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 13 00:02:42.835451 systemd[1]: Switching root. Aug 13 00:02:42.871528 systemd-journald[244]: Journal stopped Aug 13 00:02:43.777023 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Aug 13 00:02:43.777077 kernel: SELinux: policy capability network_peer_controls=1 Aug 13 00:02:43.777089 kernel: SELinux: policy capability open_perms=1 Aug 13 00:02:43.777098 kernel: SELinux: policy capability extended_socket_class=1 Aug 13 00:02:43.777111 kernel: SELinux: policy capability always_check_network=0 Aug 13 00:02:43.777121 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 13 00:02:43.777133 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 13 00:02:43.777142 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 13 00:02:43.777153 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 13 00:02:43.777165 kernel: SELinux: policy capability userspace_initial_context=0 Aug 13 00:02:43.777175 kernel: audit: type=1403 audit(1755043363.023:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 13 00:02:43.777185 systemd[1]: Successfully loaded SELinux policy in 35.475ms. Aug 13 00:02:43.777206 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.085ms. Aug 13 00:02:43.777217 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) Aug 13 00:02:43.777228 systemd[1]: Detected virtualization kvm. Aug 13 00:02:43.777238 systemd[1]: Detected architecture arm64. Aug 13 00:02:43.777252 systemd[1]: Detected first boot. Aug 13 00:02:43.777265 systemd[1]: Initializing machine ID from VM UUID. Aug 13 00:02:43.777275 kernel: NET: Registered PF_VSOCK protocol family Aug 13 00:02:43.777285 zram_generator::config[1084]: No configuration found. Aug 13 00:02:43.777297 systemd[1]: Populated /etc with preset unit settings. Aug 13 00:02:43.777308 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Aug 13 00:02:43.777318 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 13 00:02:43.777329 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 13 00:02:43.777339 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 13 00:02:43.777349 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 13 00:02:43.777359 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 13 00:02:43.777369 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 13 00:02:43.777381 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 13 00:02:43.777392 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 13 00:02:43.777402 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 13 00:02:43.777413 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 13 00:02:43.777424 systemd[1]: Created slice user.slice - User and Session Slice. Aug 13 00:02:43.777433 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:02:43.777444 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:02:43.777454 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 13 00:02:43.777468 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 13 00:02:43.777480 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 13 00:02:43.777491 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 00:02:43.777501 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Aug 13 00:02:43.777511 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:02:43.777521 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:02:43.777531 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 13 00:02:43.777542 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 13 00:02:43.777552 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 13 00:02:43.777563 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 13 00:02:43.777573 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:02:43.777584 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 00:02:43.777593 systemd[1]: Reached target slices.target - Slice Units. Aug 13 00:02:43.777604 systemd[1]: Reached target swap.target - Swaps. Aug 13 00:02:43.777614 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 13 00:02:43.777624 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 13 00:02:43.777636 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Aug 13 00:02:43.777646 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:02:43.777658 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 00:02:43.777668 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:02:43.777678 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 13 00:02:43.777688 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 13 00:02:43.777706 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 13 00:02:43.777718 systemd[1]: Mounting media.mount - External Media Directory... Aug 13 00:02:43.777731 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 13 00:02:43.777742 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 13 00:02:43.777752 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 13 00:02:43.777765 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). Aug 13 00:02:43.777775 systemd[1]: Reached target machines.target - Containers. Aug 13 00:02:43.777785 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 13 00:02:43.777795 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:02:43.777805 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 00:02:43.777815 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 13 00:02:43.777825 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:02:43.777836 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 00:02:43.777847 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:02:43.777857 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 13 00:02:43.777867 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:02:43.777878 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 13 00:02:43.777889 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 13 00:02:43.777900 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 13 00:02:43.777910 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 13 00:02:43.777920 systemd[1]: Stopped systemd-fsck-usr.service. Aug 13 00:02:43.777931 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). Aug 13 00:02:43.777951 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 00:02:43.777965 kernel: loop: module loaded Aug 13 00:02:43.777974 kernel: ACPI: bus type drm_connector registered Aug 13 00:02:43.777984 kernel: fuse: init (API version 7.41) Aug 13 00:02:43.777994 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 00:02:43.778006 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 13 00:02:43.778016 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 13 00:02:43.778027 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Aug 13 00:02:43.778037 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 00:02:43.778049 systemd[1]: verity-setup.service: Deactivated successfully. Aug 13 00:02:43.778059 systemd[1]: Stopped verity-setup.service. Aug 13 00:02:43.778069 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 13 00:02:43.778079 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 13 00:02:43.778089 systemd[1]: Mounted media.mount - External Media Directory. Aug 13 00:02:43.778101 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 13 00:02:43.778138 systemd-journald[1152]: Collecting audit messages is disabled. Aug 13 00:02:43.778162 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 13 00:02:43.778173 systemd-journald[1152]: Journal started Aug 13 00:02:43.778196 systemd-journald[1152]: Runtime Journal (/run/log/journal/aa88a65a03414207884d69c4065da722) is 6M, max 48.5M, 42.4M free. Aug 13 00:02:43.476355 systemd[1]: Queued start job for default target multi-user.target. Aug 13 00:02:43.502729 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 13 00:02:43.503173 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 13 00:02:43.780621 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 00:02:43.781354 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 13 00:02:43.783989 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 13 00:02:43.785590 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:02:43.787269 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 13 00:02:43.788051 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 13 00:02:43.789656 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:02:43.789852 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:02:43.791540 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 00:02:43.791727 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 00:02:43.793380 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:02:43.793560 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:02:43.795215 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 13 00:02:43.795383 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 13 00:02:43.796876 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:02:43.797290 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:02:43.798865 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 00:02:43.800609 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 00:02:43.802492 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 13 00:02:43.804418 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Aug 13 00:02:43.819804 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 13 00:02:43.823791 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 13 00:02:43.826276 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 13 00:02:43.827548 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 13 00:02:43.827588 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 00:02:43.829809 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Aug 13 00:02:43.837026 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 13 00:02:43.838312 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:02:43.844454 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 13 00:02:43.846913 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 13 00:02:43.848297 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 00:02:43.849446 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 13 00:02:43.851001 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 00:02:43.853064 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 00:02:43.858747 systemd-journald[1152]: Time spent on flushing to /var/log/journal/aa88a65a03414207884d69c4065da722 is 23.344ms for 864 entries. Aug 13 00:02:43.858747 systemd-journald[1152]: System Journal (/var/log/journal/aa88a65a03414207884d69c4065da722) is 8M, max 195.6M, 187.6M free. Aug 13 00:02:43.901222 systemd-journald[1152]: Received client request to flush runtime journal. Aug 13 00:02:43.901278 kernel: loop0: detected capacity change from 0 to 207008 Aug 13 00:02:43.901301 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 13 00:02:43.858207 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 13 00:02:43.864175 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 13 00:02:43.868982 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:02:43.871799 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 13 00:02:43.873570 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 13 00:02:43.876420 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 13 00:02:43.881259 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 13 00:02:43.885276 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Aug 13 00:02:43.902998 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:02:43.904703 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 13 00:02:43.930126 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 13 00:02:43.931000 kernel: loop1: detected capacity change from 0 to 107312 Aug 13 00:02:43.934194 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 00:02:43.937962 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Aug 13 00:02:43.962004 kernel: loop2: detected capacity change from 0 to 138376 Aug 13 00:02:43.972375 systemd-tmpfiles[1216]: ACLs are not supported, ignoring. Aug 13 00:02:43.972391 systemd-tmpfiles[1216]: ACLs are not supported, ignoring. Aug 13 00:02:43.982991 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:02:44.001977 kernel: loop3: detected capacity change from 0 to 207008 Aug 13 00:02:44.008993 kernel: loop4: detected capacity change from 0 to 107312 Aug 13 00:02:44.017984 kernel: loop5: detected capacity change from 0 to 138376 Aug 13 00:02:44.027624 (sd-merge)[1222]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Aug 13 00:02:44.028053 (sd-merge)[1222]: Merged extensions into '/usr'. Aug 13 00:02:44.032337 systemd[1]: Reload requested from client PID 1200 ('systemd-sysext') (unit systemd-sysext.service)... Aug 13 00:02:44.032352 systemd[1]: Reloading... Aug 13 00:02:44.094035 zram_generator::config[1253]: No configuration found. Aug 13 00:02:44.188274 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. Aug 13 00:02:44.221978 ldconfig[1195]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 13 00:02:44.251432 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 13 00:02:44.251590 systemd[1]: Reloading finished in 218 ms. Aug 13 00:02:44.283687 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 13 00:02:44.288983 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 13 00:02:44.299743 systemd[1]: Starting ensure-sysext.service... Aug 13 00:02:44.301934 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 00:02:44.314603 systemd[1]: Reload requested from client PID 1283 ('systemctl') (unit ensure-sysext.service)... Aug 13 00:02:44.314619 systemd[1]: Reloading... Aug 13 00:02:44.327181 systemd-tmpfiles[1284]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Aug 13 00:02:44.327794 systemd-tmpfiles[1284]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Aug 13 00:02:44.328205 systemd-tmpfiles[1284]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 13 00:02:44.328467 systemd-tmpfiles[1284]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 13 00:02:44.329204 systemd-tmpfiles[1284]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 13 00:02:44.329503 systemd-tmpfiles[1284]: ACLs are not supported, ignoring. Aug 13 00:02:44.329620 systemd-tmpfiles[1284]: ACLs are not supported, ignoring. Aug 13 00:02:44.332227 systemd-tmpfiles[1284]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 00:02:44.332326 systemd-tmpfiles[1284]: Skipping /boot Aug 13 00:02:44.341669 systemd-tmpfiles[1284]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 00:02:44.341684 systemd-tmpfiles[1284]: Skipping /boot Aug 13 00:02:44.379401 zram_generator::config[1311]: No configuration found. Aug 13 00:02:44.448746 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. Aug 13 00:02:44.514055 systemd[1]: Reloading finished in 199 ms. Aug 13 00:02:44.537819 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 13 00:02:44.545042 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:02:44.552867 systemd[1]: Starting audit-rules.service - Load Audit Rules... Aug 13 00:02:44.555744 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 13 00:02:44.563138 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 13 00:02:44.566607 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 00:02:44.574128 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:02:44.576961 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 13 00:02:44.589701 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 13 00:02:44.593244 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 13 00:02:44.597652 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:02:44.599900 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:02:44.603437 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:02:44.607594 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:02:44.610148 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:02:44.610327 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). Aug 13 00:02:44.611931 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 13 00:02:44.614145 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:02:44.614335 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:02:44.620830 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:02:44.621841 systemd-udevd[1352]: Using default interface naming scheme 'v255'. Aug 13 00:02:44.622080 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:02:44.624866 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 13 00:02:44.631211 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:02:44.633838 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:02:44.637466 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:02:44.638741 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:02:44.638899 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). Aug 13 00:02:44.640069 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:02:44.640379 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:02:44.643619 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 00:02:44.648454 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:02:44.650478 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:02:44.657065 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 00:02:44.660525 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:02:44.662118 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:02:44.662249 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). Aug 13 00:02:44.665836 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 00:02:44.668524 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 13 00:02:44.673011 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 13 00:02:44.674999 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 13 00:02:44.677507 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:02:44.678008 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:02:44.680629 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:02:44.682112 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:02:44.688003 systemd[1]: Finished ensure-sysext.service. Aug 13 00:02:44.697187 augenrules[1418]: No rules Aug 13 00:02:44.697971 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 00:02:44.698217 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 00:02:44.702488 systemd[1]: audit-rules.service: Deactivated successfully. Aug 13 00:02:44.702749 systemd[1]: Finished audit-rules.service - Load Audit Rules. Aug 13 00:02:44.718672 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Aug 13 00:02:44.730498 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 00:02:44.739120 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 13 00:02:44.740311 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). Aug 13 00:02:44.744906 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:02:44.748133 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:02:44.758386 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 13 00:02:44.765017 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 13 00:02:44.768084 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 00:02:44.791904 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 13 00:02:44.863046 systemd-resolved[1351]: Positive Trust Anchors: Aug 13 00:02:44.863068 systemd-resolved[1351]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 00:02:44.863100 systemd-resolved[1351]: 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 Aug 13 00:02:44.879239 systemd-resolved[1351]: Defaulting to hostname 'linux'. Aug 13 00:02:44.879571 systemd-networkd[1401]: lo: Link UP Aug 13 00:02:44.879574 systemd-networkd[1401]: lo: Gained carrier Aug 13 00:02:44.880643 systemd-networkd[1401]: Enumeration completed Aug 13 00:02:44.880796 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 00:02:44.882862 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 00:02:44.884547 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 13 00:02:44.885880 systemd[1]: Reached target network.target - Network. Aug 13 00:02:44.885921 systemd-networkd[1401]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:02:44.885926 systemd-networkd[1401]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 00:02:44.886633 systemd-networkd[1401]: eth0: Link UP Aug 13 00:02:44.886769 systemd-networkd[1401]: eth0: Gained carrier Aug 13 00:02:44.886789 systemd-networkd[1401]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:02:44.887599 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:02:44.889485 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 00:02:44.891356 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 13 00:02:44.892719 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 13 00:02:44.895143 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 13 00:02:44.896450 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 13 00:02:44.896483 systemd[1]: Reached target paths.target - Path Units. Aug 13 00:02:44.897990 systemd[1]: Reached target time-set.target - System Time Set. Aug 13 00:02:44.899313 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 13 00:02:44.902271 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 13 00:02:44.903674 systemd[1]: Reached target timers.target - Timer Units. Aug 13 00:02:44.906194 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 13 00:02:44.908861 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 13 00:02:44.914500 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Aug 13 00:02:44.918340 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Aug 13 00:02:44.919771 systemd[1]: Reached target ssh-access.target - SSH Access Available. Aug 13 00:02:44.927241 systemd-networkd[1401]: eth0: DHCPv4 address 10.0.0.143/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 13 00:02:44.930221 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 13 00:02:44.932206 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Aug 13 00:02:44.934480 systemd-timesyncd[1439]: Network configuration changed, trying to establish connection. Aug 13 00:02:44.935920 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Aug 13 00:02:45.380545 systemd-resolved[1351]: Clock change detected. Flushing caches. Aug 13 00:02:45.380586 systemd-timesyncd[1439]: Contacted time server 10.0.0.1:123 (10.0.0.1). Aug 13 00:02:45.380641 systemd-timesyncd[1439]: Initial clock synchronization to Wed 2025-08-13 00:02:45.380484 UTC. Aug 13 00:02:45.382735 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 13 00:02:45.384894 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 13 00:02:45.392915 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 00:02:45.394034 systemd[1]: Reached target basic.target - Basic System. Aug 13 00:02:45.395269 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 13 00:02:45.395300 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 13 00:02:45.398037 systemd[1]: Starting containerd.service - containerd container runtime... Aug 13 00:02:45.401066 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 13 00:02:45.403385 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 13 00:02:45.407094 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 13 00:02:45.420033 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 13 00:02:45.421451 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). Aug 13 00:02:45.422679 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 13 00:02:45.424823 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 13 00:02:45.428534 jq[1468]: false Aug 13 00:02:45.429269 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 13 00:02:45.442219 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 13 00:02:45.444245 extend-filesystems[1469]: Found /dev/vda6 Aug 13 00:02:45.444548 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:02:45.447055 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 13 00:02:45.447557 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 13 00:02:45.449361 systemd[1]: Starting update-engine.service - Update Engine... Aug 13 00:02:45.453555 extend-filesystems[1469]: Found /dev/vda9 Aug 13 00:02:45.455778 extend-filesystems[1469]: Checking size of /dev/vda9 Aug 13 00:02:45.455412 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 13 00:02:45.461203 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Aug 13 00:02:45.470513 jq[1487]: true Aug 13 00:02:45.471096 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 13 00:02:45.473141 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 13 00:02:45.473347 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 13 00:02:45.473616 systemd[1]: motdgen.service: Deactivated successfully. Aug 13 00:02:45.473789 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 13 00:02:45.477593 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 13 00:02:45.477803 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 13 00:02:45.501315 jq[1494]: true Aug 13 00:02:45.509288 (ntainerd)[1503]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 13 00:02:45.516662 extend-filesystems[1469]: Resized partition /dev/vda9 Aug 13 00:02:45.524217 extend-filesystems[1509]: resize2fs 1.47.2 (1-Jan-2025) Aug 13 00:02:45.540791 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Aug 13 00:02:45.575134 dbus-daemon[1466]: [system] SELinux support is enabled Aug 13 00:02:45.575369 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 13 00:02:45.576115 systemd-logind[1481]: Watching system buttons on /dev/input/event0 (Power Button) Aug 13 00:02:45.579104 systemd-logind[1481]: New seat seat0. Aug 13 00:02:45.583783 update_engine[1485]: I20250813 00:02:45.581171 1485 main.cc:92] Flatcar Update Engine starting Aug 13 00:02:45.579350 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). Aug 13 00:02:45.579392 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 13 00:02:45.581021 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). Aug 13 00:02:45.581068 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 13 00:02:45.588229 systemd[1]: Started systemd-logind.service - User Login Management. Aug 13 00:02:45.594991 systemd[1]: Started update-engine.service - Update Engine. Aug 13 00:02:45.595314 update_engine[1485]: I20250813 00:02:45.595263 1485 update_check_scheduler.cc:74] Next update check in 4m45s Aug 13 00:02:45.599317 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 13 00:02:45.618972 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:02:45.631920 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Aug 13 00:02:45.654938 extend-filesystems[1509]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Aug 13 00:02:45.654938 extend-filesystems[1509]: old_desc_blocks = 1, new_desc_blocks = 1 Aug 13 00:02:45.654938 extend-filesystems[1509]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Aug 13 00:02:45.669125 extend-filesystems[1469]: Resized filesystem in /dev/vda9 Aug 13 00:02:45.670129 bash[1527]: Updated "/home/core/.ssh/authorized_keys" Aug 13 00:02:45.656701 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 13 00:02:45.658740 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 13 00:02:45.662351 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 13 00:02:45.665174 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 13 00:02:45.718226 locksmithd[1529]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 13 00:02:45.839551 containerd[1503]: time="2025-08-13T00:02:45Z" 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 Aug 13 00:02:45.840458 containerd[1503]: time="2025-08-13T00:02:45.840415693Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Aug 13 00:02:45.850451 containerd[1503]: time="2025-08-13T00:02:45.850394413Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="11.28µs" Aug 13 00:02:45.850451 containerd[1503]: time="2025-08-13T00:02:45.850438733Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Aug 13 00:02:45.850451 containerd[1503]: time="2025-08-13T00:02:45.850458453Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Aug 13 00:02:45.850680 containerd[1503]: time="2025-08-13T00:02:45.850638893Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Aug 13 00:02:45.850680 containerd[1503]: time="2025-08-13T00:02:45.850673813Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Aug 13 00:02:45.850723 containerd[1503]: time="2025-08-13T00:02:45.850703453Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Aug 13 00:02:45.850774 containerd[1503]: time="2025-08-13T00:02:45.850758973Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Aug 13 00:02:45.850794 containerd[1503]: time="2025-08-13T00:02:45.850777653Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Aug 13 00:02:45.851066 containerd[1503]: time="2025-08-13T00:02:45.851037333Z" 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 Aug 13 00:02:45.851066 containerd[1503]: time="2025-08-13T00:02:45.851059013Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 13 00:02:45.851112 containerd[1503]: time="2025-08-13T00:02:45.851071093Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Aug 13 00:02:45.851112 containerd[1503]: time="2025-08-13T00:02:45.851080253Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Aug 13 00:02:45.851166 containerd[1503]: time="2025-08-13T00:02:45.851151653Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Aug 13 00:02:45.851369 containerd[1503]: time="2025-08-13T00:02:45.851341973Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Aug 13 00:02:45.851391 containerd[1503]: time="2025-08-13T00:02:45.851379253Z" 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 Aug 13 00:02:45.851409 containerd[1503]: time="2025-08-13T00:02:45.851390893Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Aug 13 00:02:45.851441 containerd[1503]: time="2025-08-13T00:02:45.851425813Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Aug 13 00:02:45.853187 containerd[1503]: time="2025-08-13T00:02:45.853148373Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Aug 13 00:02:45.853290 containerd[1503]: time="2025-08-13T00:02:45.853274053Z" level=info msg="metadata content store policy set" policy=shared Aug 13 00:02:45.860196 containerd[1503]: time="2025-08-13T00:02:45.860148013Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Aug 13 00:02:45.860295 containerd[1503]: time="2025-08-13T00:02:45.860213173Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Aug 13 00:02:45.860295 containerd[1503]: time="2025-08-13T00:02:45.860231653Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Aug 13 00:02:45.860295 containerd[1503]: time="2025-08-13T00:02:45.860244173Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Aug 13 00:02:45.860295 containerd[1503]: time="2025-08-13T00:02:45.860257893Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Aug 13 00:02:45.860295 containerd[1503]: time="2025-08-13T00:02:45.860269813Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Aug 13 00:02:45.860295 containerd[1503]: time="2025-08-13T00:02:45.860282573Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Aug 13 00:02:45.860295 containerd[1503]: time="2025-08-13T00:02:45.860295213Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Aug 13 00:02:45.860428 containerd[1503]: time="2025-08-13T00:02:45.860307613Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Aug 13 00:02:45.860428 containerd[1503]: time="2025-08-13T00:02:45.860318573Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Aug 13 00:02:45.860428 containerd[1503]: time="2025-08-13T00:02:45.860328293Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Aug 13 00:02:45.860428 containerd[1503]: time="2025-08-13T00:02:45.860342093Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Aug 13 00:02:45.860595 containerd[1503]: time="2025-08-13T00:02:45.860502773Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Aug 13 00:02:45.860595 containerd[1503]: time="2025-08-13T00:02:45.860570773Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Aug 13 00:02:45.860595 containerd[1503]: time="2025-08-13T00:02:45.860590133Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Aug 13 00:02:45.860659 containerd[1503]: time="2025-08-13T00:02:45.860602773Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Aug 13 00:02:45.860659 containerd[1503]: time="2025-08-13T00:02:45.860628053Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Aug 13 00:02:45.860659 containerd[1503]: time="2025-08-13T00:02:45.860639573Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Aug 13 00:02:45.860731 containerd[1503]: time="2025-08-13T00:02:45.860664493Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Aug 13 00:02:45.860731 containerd[1503]: time="2025-08-13T00:02:45.860683373Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Aug 13 00:02:45.860731 containerd[1503]: time="2025-08-13T00:02:45.860698613Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Aug 13 00:02:45.860731 containerd[1503]: time="2025-08-13T00:02:45.860711493Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Aug 13 00:02:45.860731 containerd[1503]: time="2025-08-13T00:02:45.860722813Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Aug 13 00:02:45.861044 containerd[1503]: time="2025-08-13T00:02:45.861019053Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Aug 13 00:02:45.861044 containerd[1503]: time="2025-08-13T00:02:45.861043093Z" level=info msg="Start snapshots syncer" Aug 13 00:02:45.861127 containerd[1503]: time="2025-08-13T00:02:45.861078733Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Aug 13 00:02:45.861471 containerd[1503]: time="2025-08-13T00:02:45.861352573Z" 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\"}" Aug 13 00:02:45.861605 containerd[1503]: time="2025-08-13T00:02:45.861482853Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Aug 13 00:02:45.861605 containerd[1503]: time="2025-08-13T00:02:45.861600213Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Aug 13 00:02:45.861819 containerd[1503]: time="2025-08-13T00:02:45.861795853Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Aug 13 00:02:45.861854 containerd[1503]: time="2025-08-13T00:02:45.861829653Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Aug 13 00:02:45.861854 containerd[1503]: time="2025-08-13T00:02:45.861842933Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Aug 13 00:02:45.861891 containerd[1503]: time="2025-08-13T00:02:45.861853733Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Aug 13 00:02:45.861891 containerd[1503]: time="2025-08-13T00:02:45.861866853Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Aug 13 00:02:45.861891 containerd[1503]: time="2025-08-13T00:02:45.861885093Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Aug 13 00:02:45.861971 containerd[1503]: time="2025-08-13T00:02:45.861923133Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Aug 13 00:02:45.861971 containerd[1503]: time="2025-08-13T00:02:45.861952333Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Aug 13 00:02:45.861971 containerd[1503]: time="2025-08-13T00:02:45.861966013Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Aug 13 00:02:45.862026 containerd[1503]: time="2025-08-13T00:02:45.861986133Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Aug 13 00:02:45.862044 containerd[1503]: time="2025-08-13T00:02:45.862031053Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Aug 13 00:02:45.862061 containerd[1503]: time="2025-08-13T00:02:45.862046573Z" 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 Aug 13 00:02:45.862061 containerd[1503]: time="2025-08-13T00:02:45.862055973Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Aug 13 00:02:45.862097 containerd[1503]: time="2025-08-13T00:02:45.862065933Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Aug 13 00:02:45.862140 containerd[1503]: time="2025-08-13T00:02:45.862073933Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Aug 13 00:02:45.862161 containerd[1503]: time="2025-08-13T00:02:45.862142893Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Aug 13 00:02:45.862161 containerd[1503]: time="2025-08-13T00:02:45.862157133Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Aug 13 00:02:45.862272 containerd[1503]: time="2025-08-13T00:02:45.862261093Z" level=info msg="runtime interface created" Aug 13 00:02:45.862291 containerd[1503]: time="2025-08-13T00:02:45.862271293Z" level=info msg="created NRI interface" Aug 13 00:02:45.862291 containerd[1503]: time="2025-08-13T00:02:45.862283373Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Aug 13 00:02:45.862328 containerd[1503]: time="2025-08-13T00:02:45.862297053Z" level=info msg="Connect containerd service" Aug 13 00:02:45.862350 containerd[1503]: time="2025-08-13T00:02:45.862328373Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 13 00:02:45.863332 containerd[1503]: time="2025-08-13T00:02:45.863294773Z" 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" Aug 13 00:02:46.006174 containerd[1503]: time="2025-08-13T00:02:46.006048853Z" level=info msg="Start subscribing containerd event" Aug 13 00:02:46.006174 containerd[1503]: time="2025-08-13T00:02:46.006099133Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 13 00:02:46.006174 containerd[1503]: time="2025-08-13T00:02:46.006123533Z" level=info msg="Start recovering state" Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006368573Z" level=info msg="Start event monitor" Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006386053Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006409733Z" level=info msg="Start cni network conf syncer for default" Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006514693Z" level=info msg="Start streaming server" Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006529333Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006537093Z" level=info msg="runtime interface starting up..." Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006542893Z" level=info msg="starting plugins..." Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006563973Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Aug 13 00:02:46.007269 containerd[1503]: time="2025-08-13T00:02:46.006709253Z" level=info msg="containerd successfully booted in 0.167656s" Aug 13 00:02:46.007686 systemd[1]: Started containerd.service - containerd container runtime. Aug 13 00:02:46.769905 sshd_keygen[1489]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 13 00:02:46.796428 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 13 00:02:46.802003 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 13 00:02:46.826309 systemd[1]: issuegen.service: Deactivated successfully. Aug 13 00:02:46.826549 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 13 00:02:46.830947 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 13 00:02:46.861052 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 13 00:02:46.865217 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 13 00:02:46.871634 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Aug 13 00:02:46.873788 systemd[1]: Reached target getty.target - Login Prompts. Aug 13 00:02:47.343119 systemd-networkd[1401]: eth0: Gained IPv6LL Aug 13 00:02:47.345962 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 13 00:02:47.348606 systemd[1]: Reached target network-online.target - Network is Online. Aug 13 00:02:47.351777 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 13 00:02:47.355349 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 00:02:47.366434 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 13 00:02:47.391237 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 13 00:02:47.391496 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 13 00:02:47.394751 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 13 00:02:47.398080 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 13 00:02:48.094391 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:02:48.096687 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 13 00:02:48.098621 systemd[1]: Startup finished in 2.334s (kernel) + 5.356s (initrd) + 4.678s (userspace) = 12.370s. Aug 13 00:02:48.113328 (kubelet)[1597]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 13 00:02:48.586475 kubelet[1597]: E0813 00:02:48.586351 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" Aug 13 00:02:48.589024 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 13 00:02:48.589167 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 13 00:02:48.589493 systemd[1]: kubelet.service: Consumed 842ms CPU time, 256.2M memory peak. Aug 13 00:02:51.553858 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 13 00:02:51.555052 systemd[1]: Started sshd@0-10.0.0.143:22-10.0.0.1:44650.service - OpenSSH per-connection server daemon (10.0.0.1:44650). Aug 13 00:02:51.651707 sshd[1612]: Accepted publickey for core from 10.0.0.1 port 44650 ssh2: RSA SHA256:Bk7uJ3DDK+Y7ogf3dGZLP447i4jtLnzkQos038lnf/E Aug 13 00:02:51.653792 sshd-session[1612]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:51.660275 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 13 00:02:51.661225 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 13 00:02:51.666776 systemd-logind[1481]: New session 1 of user core. Aug 13 00:02:51.702299 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 13 00:02:51.705311 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 13 00:02:51.726066 (systemd)[1616]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 13 00:02:51.728445 systemd-logind[1481]: New session c1 of user core. Aug 13 00:02:51.864244 systemd[1616]: Queued start job for default target default.target. Aug 13 00:02:51.888031 systemd[1616]: Created slice app.slice - User Application Slice. Aug 13 00:02:51.888067 systemd[1616]: Reached target paths.target - Paths. Aug 13 00:02:51.888105 systemd[1616]: Reached target timers.target - Timers. Aug 13 00:02:51.889544 systemd[1616]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 13 00:02:51.902168 systemd[1616]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:02:51.902304 systemd[1616]: Reached target sockets.target - Sockets. Aug 13 00:02:51.902352 systemd[1616]: Reached target basic.target - Basic System. Aug 13 00:02:51.902381 systemd[1616]: Reached target default.target - Main User Target. Aug 13 00:02:51.902410 systemd[1616]: Startup finished in 166ms. Aug 13 00:02:51.902818 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 13 00:02:51.905884 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 13 00:02:51.966727 systemd[1]: Started sshd@1-10.0.0.143:22-10.0.0.1:44666.service - OpenSSH per-connection server daemon (10.0.0.1:44666). Aug 13 00:02:52.042275 sshd[1627]: Accepted publickey for core from 10.0.0.1 port 44666 ssh2: RSA SHA256:Bk7uJ3DDK+Y7ogf3dGZLP447i4jtLnzkQos038lnf/E Aug 13 00:02:52.044292 sshd-session[1627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:52.052290 systemd-logind[1481]: New session 2 of user core. Aug 13 00:02:52.065204 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 13 00:02:52.118930 sshd[1629]: Connection closed by 10.0.0.1 port 44666 Aug 13 00:02:52.119533 sshd-session[1627]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:52.138154 systemd[1]: sshd@1-10.0.0.143:22-10.0.0.1:44666.service: Deactivated successfully. Aug 13 00:02:52.141789 systemd[1]: session-2.scope: Deactivated successfully. Aug 13 00:02:52.143524 systemd-logind[1481]: Session 2 logged out. Waiting for processes to exit. Aug 13 00:02:52.146402 systemd[1]: Started sshd@2-10.0.0.143:22-10.0.0.1:44682.service - OpenSSH per-connection server daemon (10.0.0.1:44682). Aug 13 00:02:52.148742 systemd-logind[1481]: Removed session 2. Aug 13 00:02:52.198416 sshd[1635]: Accepted publickey for core from 10.0.0.1 port 44682 ssh2: RSA SHA256:Bk7uJ3DDK+Y7ogf3dGZLP447i4jtLnzkQos038lnf/E Aug 13 00:02:52.199238 sshd-session[1635]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:52.204084 systemd-logind[1481]: New session 3 of user core. Aug 13 00:02:52.225171 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 13 00:02:52.274530 sshd[1637]: Connection closed by 10.0.0.1 port 44682 Aug 13 00:02:52.274928 sshd-session[1635]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:52.290554 systemd[1]: sshd@2-10.0.0.143:22-10.0.0.1:44682.service: Deactivated successfully. Aug 13 00:02:52.294210 systemd[1]: session-3.scope: Deactivated successfully. Aug 13 00:02:52.295256 systemd-logind[1481]: Session 3 logged out. Waiting for processes to exit. Aug 13 00:02:52.300567 systemd[1]: Started sshd@3-10.0.0.143:22-10.0.0.1:44694.service - OpenSSH per-connection server daemon (10.0.0.1:44694). Aug 13 00:02:52.301414 systemd-logind[1481]: Removed session 3. Aug 13 00:02:52.371438 sshd[1643]: Accepted publickey for core from 10.0.0.1 port 44694 ssh2: RSA SHA256:Bk7uJ3DDK+Y7ogf3dGZLP447i4jtLnzkQos038lnf/E Aug 13 00:02:52.372541 sshd-session[1643]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:52.379249 systemd-logind[1481]: New session 4 of user core. Aug 13 00:02:52.392158 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 13 00:02:52.451291 sshd[1645]: Connection closed by 10.0.0.1 port 44694 Aug 13 00:02:52.451149 sshd-session[1643]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:52.462498 systemd[1]: sshd@3-10.0.0.143:22-10.0.0.1:44694.service: Deactivated successfully. Aug 13 00:02:52.464167 systemd[1]: session-4.scope: Deactivated successfully. Aug 13 00:02:52.466638 systemd-logind[1481]: Session 4 logged out. Waiting for processes to exit. Aug 13 00:02:52.471935 systemd[1]: Started sshd@4-10.0.0.143:22-10.0.0.1:48420.service - OpenSSH per-connection server daemon (10.0.0.1:48420). Aug 13 00:02:52.472575 systemd-logind[1481]: Removed session 4. Aug 13 00:02:52.545113 sshd[1651]: Accepted publickey for core from 10.0.0.1 port 48420 ssh2: RSA SHA256:Bk7uJ3DDK+Y7ogf3dGZLP447i4jtLnzkQos038lnf/E Aug 13 00:02:52.546435 sshd-session[1651]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:02:52.551927 systemd-logind[1481]: New session 5 of user core. Aug 13 00:02:52.566960 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 13 00:02:52.642094 sudo[1654]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 13 00:02:52.642886 sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 00:02:52.656291 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 13 00:02:52.696677 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 13 00:02:52.696940 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 13 00:02:53.444048 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:02:53.444190 systemd[1]: kubelet.service: Consumed 842ms CPU time, 256.2M memory peak. Aug 13 00:02:53.447487 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 00:02:53.472023 systemd[1]: Reload requested from client PID 1695 ('systemctl') (unit session-5.scope)... Aug 13 00:02:53.472034 systemd[1]: Reloading... Aug 13 00:02:53.546006 zram_generator::config[1736]: No configuration found. Aug 13 00:02:53.639465 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. Aug 13 00:02:53.724599 systemd[1]: Reloading finished in 252 ms. Aug 13 00:02:53.780592 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Aug 13 00:02:53.780687 systemd[1]: kubelet.service: Failed with result 'signal'. Aug 13 00:02:53.780958 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:02:53.781012 systemd[1]: kubelet.service: Consumed 100ms CPU time, 95.1M memory peak. Aug 13 00:02:53.782733 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 00:02:53.909891 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:02:53.913731 (kubelet)[1781]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 13 00:02:53.956979 kubelet[1781]: 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. Aug 13 00:02:53.956979 kubelet[1781]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Aug 13 00:02:53.956979 kubelet[1781]: 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. Aug 13 00:02:53.956979 kubelet[1781]: I0813 00:02:53.956368 1781 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Aug 13 00:02:54.434934 kubelet[1781]: I0813 00:02:54.434876 1781 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Aug 13 00:02:54.434934 kubelet[1781]: I0813 00:02:54.434919 1781 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 13 00:02:54.435221 kubelet[1781]: I0813 00:02:54.435192 1781 server.go:954] "Client rotation is on, will bootstrap in background" Aug 13 00:02:54.469946 kubelet[1781]: I0813 00:02:54.469852 1781 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 13 00:02:54.477377 kubelet[1781]: I0813 00:02:54.477323 1781 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Aug 13 00:02:54.480560 kubelet[1781]: I0813 00:02:54.480470 1781 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 13 00:02:54.481485 kubelet[1781]: I0813 00:02:54.481418 1781 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 13 00:02:54.481668 kubelet[1781]: I0813 00:02:54.481472 1781 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.143","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Aug 13 00:02:54.481777 kubelet[1781]: I0813 00:02:54.481732 1781 topology_manager.go:138] "Creating topology manager with none policy" Aug 13 00:02:54.481777 kubelet[1781]: I0813 00:02:54.481742 1781 container_manager_linux.go:304] "Creating device plugin manager" Aug 13 00:02:54.481999 kubelet[1781]: I0813 00:02:54.481970 1781 state_mem.go:36] "Initialized new in-memory state store" Aug 13 00:02:54.487857 kubelet[1781]: I0813 00:02:54.487820 1781 kubelet.go:446] "Attempting to sync node with API server" Aug 13 00:02:54.487857 kubelet[1781]: I0813 00:02:54.487857 1781 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 13 00:02:54.489966 kubelet[1781]: I0813 00:02:54.489934 1781 kubelet.go:352] "Adding apiserver pod source" Aug 13 00:02:54.489966 kubelet[1781]: I0813 00:02:54.489973 1781 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 13 00:02:54.489966 kubelet[1781]: E0813 00:02:54.490046 1781 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:02:54.489966 kubelet[1781]: E0813 00:02:54.490086 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:02:54.501139 kubelet[1781]: I0813 00:02:54.501113 1781 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Aug 13 00:02:54.502461 kubelet[1781]: I0813 00:02:54.502433 1781 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Aug 13 00:02:54.502937 kubelet[1781]: W0813 00:02:54.502563 1781 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 13 00:02:54.503231 kubelet[1781]: W0813 00:02:54.503200 1781 reflector.go:569] 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 Aug 13 00:02:54.503270 kubelet[1781]: E0813 00:02:54.503243 1781 reflector.go:166] "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" Aug 13 00:02:54.503371 kubelet[1781]: W0813 00:02:54.503354 1781 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.143" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 13 00:02:54.503402 kubelet[1781]: E0813 00:02:54.503373 1781 reflector.go:166] "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.143\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Aug 13 00:02:54.503573 kubelet[1781]: I0813 00:02:54.503547 1781 watchdog_linux.go:99] "Systemd watchdog is not enabled" Aug 13 00:02:54.503615 kubelet[1781]: I0813 00:02:54.503586 1781 server.go:1287] "Started kubelet" Aug 13 00:02:54.504359 kubelet[1781]: I0813 00:02:54.504296 1781 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Aug 13 00:02:54.507649 kubelet[1781]: I0813 00:02:54.507603 1781 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 13 00:02:54.509261 kubelet[1781]: I0813 00:02:54.509194 1781 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Aug 13 00:02:54.509428 kubelet[1781]: I0813 00:02:54.509409 1781 server.go:479] "Adding debug handlers to kubelet server" Aug 13 00:02:54.509587 kubelet[1781]: I0813 00:02:54.509553 1781 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Aug 13 00:02:54.509863 kubelet[1781]: I0813 00:02:54.509838 1781 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 13 00:02:54.510671 kubelet[1781]: I0813 00:02:54.510649 1781 volume_manager.go:297] "Starting Kubelet Volume Manager" Aug 13 00:02:54.512228 kubelet[1781]: E0813 00:02:54.512203 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:54.512426 kubelet[1781]: I0813 00:02:54.512402 1781 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Aug 13 00:02:54.512607 kubelet[1781]: I0813 00:02:54.512594 1781 reconciler.go:26] "Reconciler: start to sync state" Aug 13 00:02:54.514573 kubelet[1781]: I0813 00:02:54.514510 1781 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 Aug 13 00:02:54.515082 kubelet[1781]: E0813 00:02:54.515036 1781 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Aug 13 00:02:54.515997 kubelet[1781]: I0813 00:02:54.515975 1781 factory.go:221] Registration of the containerd container factory successfully Aug 13 00:02:54.515997 kubelet[1781]: I0813 00:02:54.515993 1781 factory.go:221] Registration of the systemd container factory successfully Aug 13 00:02:54.528660 kubelet[1781]: E0813 00:02:54.528511 1781 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.143\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Aug 13 00:02:54.529661 kubelet[1781]: E0813 00:02:54.528603 1781 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.143.185b2aa686b01d65 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.143,UID:10.0.0.143,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.143,},FirstTimestamp:2025-08-13 00:02:54.503566693 +0000 UTC m=+0.586820161,LastTimestamp:2025-08-13 00:02:54.503566693 +0000 UTC m=+0.586820161,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.143,}" Aug 13 00:02:54.529661 kubelet[1781]: W0813 00:02:54.529443 1781 reflector.go:569] 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 Aug 13 00:02:54.529661 kubelet[1781]: E0813 00:02:54.529476 1781 reflector.go:166] "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" Aug 13 00:02:54.532955 kubelet[1781]: E0813 00:02:54.532829 1781 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.143.185b2aa6875ee6dd default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.143,UID:10.0.0.143,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.143,},FirstTimestamp:2025-08-13 00:02:54.515021533 +0000 UTC m=+0.598275001,LastTimestamp:2025-08-13 00:02:54.515021533 +0000 UTC m=+0.598275001,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.143,}" Aug 13 00:02:54.535549 kubelet[1781]: I0813 00:02:54.535510 1781 cpu_manager.go:221] "Starting CPU manager" policy="none" Aug 13 00:02:54.535737 kubelet[1781]: I0813 00:02:54.535529 1781 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Aug 13 00:02:54.535737 kubelet[1781]: I0813 00:02:54.535659 1781 state_mem.go:36] "Initialized new in-memory state store" Aug 13 00:02:54.544310 kubelet[1781]: E0813 00:02:54.544094 1781 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.143.185b2aa6888b84ad default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.143,UID:10.0.0.143,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientMemory,Message:Node 10.0.0.143 status is now: NodeHasSufficientMemory,Source:EventSource{Component:kubelet,Host:10.0.0.143,},FirstTimestamp:2025-08-13 00:02:54.534722733 +0000 UTC m=+0.617976121,LastTimestamp:2025-08-13 00:02:54.534722733 +0000 UTC m=+0.617976121,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.143,}" Aug 13 00:02:54.551936 kubelet[1781]: E0813 00:02:54.551818 1781 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.143.185b2aa6888bb405 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.143,UID:10.0.0.143,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasNoDiskPressure,Message:Node 10.0.0.143 status is now: NodeHasNoDiskPressure,Source:EventSource{Component:kubelet,Host:10.0.0.143,},FirstTimestamp:2025-08-13 00:02:54.534734853 +0000 UTC m=+0.617988241,LastTimestamp:2025-08-13 00:02:54.534734853 +0000 UTC m=+0.617988241,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.143,}" Aug 13 00:02:54.563521 kubelet[1781]: E0813 00:02:54.563413 1781 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.143.185b2aa6888bc0d5 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.143,UID:10.0.0.143,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientPID,Message:Node 10.0.0.143 status is now: NodeHasSufficientPID,Source:EventSource{Component:kubelet,Host:10.0.0.143,},FirstTimestamp:2025-08-13 00:02:54.534738133 +0000 UTC m=+0.617991521,LastTimestamp:2025-08-13 00:02:54.534738133 +0000 UTC m=+0.617991521,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.143,}" Aug 13 00:02:54.613161 kubelet[1781]: E0813 00:02:54.613121 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:54.713728 kubelet[1781]: E0813 00:02:54.713603 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:54.741380 kubelet[1781]: E0813 00:02:54.741329 1781 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.143\" not found" node="10.0.0.143" Aug 13 00:02:54.814236 kubelet[1781]: E0813 00:02:54.814197 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:54.906830 kubelet[1781]: I0813 00:02:54.906780 1781 policy_none.go:49] "None policy: Start" Aug 13 00:02:54.906830 kubelet[1781]: I0813 00:02:54.906812 1781 memory_manager.go:186] "Starting memorymanager" policy="None" Aug 13 00:02:54.906830 kubelet[1781]: I0813 00:02:54.906826 1781 state_mem.go:35] "Initializing new in-memory state store" Aug 13 00:02:54.914709 kubelet[1781]: E0813 00:02:54.914637 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:54.925954 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Aug 13 00:02:54.933421 kubelet[1781]: I0813 00:02:54.931719 1781 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 13 00:02:54.933421 kubelet[1781]: I0813 00:02:54.933156 1781 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 13 00:02:54.933421 kubelet[1781]: I0813 00:02:54.933179 1781 status_manager.go:227] "Starting to sync pod status with apiserver" Aug 13 00:02:54.933421 kubelet[1781]: I0813 00:02:54.933200 1781 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Aug 13 00:02:54.933421 kubelet[1781]: I0813 00:02:54.933207 1781 kubelet.go:2382] "Starting kubelet main sync loop" Aug 13 00:02:54.933421 kubelet[1781]: E0813 00:02:54.933255 1781 kubelet.go:2406] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Aug 13 00:02:54.941716 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Aug 13 00:02:54.946175 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Aug 13 00:02:54.968077 kubelet[1781]: I0813 00:02:54.967954 1781 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 13 00:02:54.968379 kubelet[1781]: I0813 00:02:54.968170 1781 eviction_manager.go:189] "Eviction manager: starting control loop" Aug 13 00:02:54.968379 kubelet[1781]: I0813 00:02:54.968181 1781 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Aug 13 00:02:54.969255 kubelet[1781]: I0813 00:02:54.968442 1781 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 13 00:02:54.972728 kubelet[1781]: E0813 00:02:54.972701 1781 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Aug 13 00:02:54.972957 kubelet[1781]: E0813 00:02:54.972944 1781 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.143\" not found" Aug 13 00:02:55.070216 kubelet[1781]: I0813 00:02:55.070186 1781 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.143" Aug 13 00:02:55.076097 kubelet[1781]: I0813 00:02:55.076058 1781 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.143" Aug 13 00:02:55.076097 kubelet[1781]: E0813 00:02:55.076102 1781 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.143\": node \"10.0.0.143\" not found" Aug 13 00:02:55.095926 kubelet[1781]: E0813 00:02:55.095874 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:55.197226 kubelet[1781]: E0813 00:02:55.197176 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:55.298026 kubelet[1781]: E0813 00:02:55.297895 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:55.398845 kubelet[1781]: E0813 00:02:55.398775 1781 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.143\" not found" Aug 13 00:02:55.437598 kubelet[1781]: I0813 00:02:55.437552 1781 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Aug 13 00:02:55.437767 kubelet[1781]: W0813 00:02:55.437723 1781 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Aug 13 00:02:55.437895 kubelet[1781]: W0813 00:02:55.437784 1781 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Aug 13 00:02:55.452445 sudo[1654]: pam_unix(sudo:session): session closed for user root Aug 13 00:02:55.455970 sshd[1653]: Connection closed by 10.0.0.1 port 48420 Aug 13 00:02:55.456433 sshd-session[1651]: pam_unix(sshd:session): session closed for user core Aug 13 00:02:55.459778 systemd[1]: sshd@4-10.0.0.143:22-10.0.0.1:48420.service: Deactivated successfully. Aug 13 00:02:55.462011 systemd[1]: session-5.scope: Deactivated successfully. Aug 13 00:02:55.463022 systemd[1]: session-5.scope: Consumed 507ms CPU time, 70.7M memory peak. Aug 13 00:02:55.464841 systemd-logind[1481]: Session 5 logged out. Waiting for processes to exit. Aug 13 00:02:55.466519 systemd-logind[1481]: Removed session 5. Aug 13 00:02:55.490262 kubelet[1781]: E0813 00:02:55.490223 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:02:55.499970 kubelet[1781]: I0813 00:02:55.499931 1781 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Aug 13 00:02:55.500290 containerd[1503]: time="2025-08-13T00:02:55.500252013Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Aug 13 00:02:55.500806 kubelet[1781]: I0813 00:02:55.500787 1781 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Aug 13 00:02:56.490523 kubelet[1781]: I0813 00:02:56.490435 1781 apiserver.go:52] "Watching apiserver" Aug 13 00:02:56.490523 kubelet[1781]: E0813 00:02:56.490461 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:02:56.507523 systemd[1]: Created slice kubepods-besteffort-pod7ece5201_728c_4c5b_9231_7916f546732e.slice - libcontainer container kubepods-besteffort-pod7ece5201_728c_4c5b_9231_7916f546732e.slice. Aug 13 00:02:56.513862 kubelet[1781]: I0813 00:02:56.513813 1781 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Aug 13 00:02:56.526419 kubelet[1781]: I0813 00:02:56.526361 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5z8pv\" (UniqueName: \"kubernetes.io/projected/7ece5201-728c-4c5b-9231-7916f546732e-kube-api-access-5z8pv\") pod \"kube-proxy-bcmnj\" (UID: \"7ece5201-728c-4c5b-9231-7916f546732e\") " pod="kube-system/kube-proxy-bcmnj" Aug 13 00:02:56.526714 kubelet[1781]: I0813 00:02:56.526567 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/ae286166-c3b1-45f1-b138-958c106c9592-cni-plugin\") pod \"kube-flannel-ds-59sd4\" (UID: \"ae286166-c3b1-45f1-b138-958c106c9592\") " pod="kube-flannel/kube-flannel-ds-59sd4" Aug 13 00:02:56.526714 kubelet[1781]: I0813 00:02:56.526601 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/ae286166-c3b1-45f1-b138-958c106c9592-flannel-cfg\") pod \"kube-flannel-ds-59sd4\" (UID: \"ae286166-c3b1-45f1-b138-958c106c9592\") " pod="kube-flannel/kube-flannel-ds-59sd4" Aug 13 00:02:56.526714 kubelet[1781]: I0813 00:02:56.526640 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/ae286166-c3b1-45f1-b138-958c106c9592-xtables-lock\") pod \"kube-flannel-ds-59sd4\" (UID: \"ae286166-c3b1-45f1-b138-958c106c9592\") " pod="kube-flannel/kube-flannel-ds-59sd4" Aug 13 00:02:56.526714 kubelet[1781]: I0813 00:02:56.526661 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/7ece5201-728c-4c5b-9231-7916f546732e-kube-proxy\") pod \"kube-proxy-bcmnj\" (UID: \"7ece5201-728c-4c5b-9231-7916f546732e\") " pod="kube-system/kube-proxy-bcmnj" Aug 13 00:02:56.526714 kubelet[1781]: I0813 00:02:56.526676 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/7ece5201-728c-4c5b-9231-7916f546732e-xtables-lock\") pod \"kube-proxy-bcmnj\" (UID: \"7ece5201-728c-4c5b-9231-7916f546732e\") " pod="kube-system/kube-proxy-bcmnj" Aug 13 00:02:56.526860 kubelet[1781]: I0813 00:02:56.526691 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/7ece5201-728c-4c5b-9231-7916f546732e-lib-modules\") pod \"kube-proxy-bcmnj\" (UID: \"7ece5201-728c-4c5b-9231-7916f546732e\") " pod="kube-system/kube-proxy-bcmnj" Aug 13 00:02:56.527025 kubelet[1781]: I0813 00:02:56.526893 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/ae286166-c3b1-45f1-b138-958c106c9592-run\") pod \"kube-flannel-ds-59sd4\" (UID: \"ae286166-c3b1-45f1-b138-958c106c9592\") " pod="kube-flannel/kube-flannel-ds-59sd4" Aug 13 00:02:56.527025 kubelet[1781]: I0813 00:02:56.526995 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/ae286166-c3b1-45f1-b138-958c106c9592-cni\") pod \"kube-flannel-ds-59sd4\" (UID: \"ae286166-c3b1-45f1-b138-958c106c9592\") " pod="kube-flannel/kube-flannel-ds-59sd4" Aug 13 00:02:56.527185 kubelet[1781]: I0813 00:02:56.527078 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2z5ww\" (UniqueName: \"kubernetes.io/projected/ae286166-c3b1-45f1-b138-958c106c9592-kube-api-access-2z5ww\") pod \"kube-flannel-ds-59sd4\" (UID: \"ae286166-c3b1-45f1-b138-958c106c9592\") " pod="kube-flannel/kube-flannel-ds-59sd4" Aug 13 00:02:56.533069 systemd[1]: Created slice kubepods-burstable-podae286166_c3b1_45f1_b138_958c106c9592.slice - libcontainer container kubepods-burstable-podae286166_c3b1_45f1_b138_958c106c9592.slice. Aug 13 00:02:56.830909 containerd[1503]: time="2025-08-13T00:02:56.830787893Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-bcmnj,Uid:7ece5201-728c-4c5b-9231-7916f546732e,Namespace:kube-system,Attempt:0,}" Aug 13 00:02:56.854849 containerd[1503]: time="2025-08-13T00:02:56.854805533Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-59sd4,Uid:ae286166-c3b1-45f1-b138-958c106c9592,Namespace:kube-flannel,Attempt:0,}" Aug 13 00:02:57.490774 kubelet[1781]: E0813 00:02:57.490738 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:02:57.492990 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2524879100.mount: Deactivated successfully. Aug 13 00:02:57.505318 containerd[1503]: time="2025-08-13T00:02:57.505169573Z" 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\"}" Aug 13 00:02:57.506975 containerd[1503]: time="2025-08-13T00:02:57.506823013Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Aug 13 00:02:57.508188 containerd[1503]: time="2025-08-13T00:02:57.507682013Z" 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\"}" Aug 13 00:02:57.508482 containerd[1503]: time="2025-08-13T00:02:57.508449493Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 13 00:02:57.509115 containerd[1503]: time="2025-08-13T00:02:57.509075453Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Aug 13 00:02:57.511749 containerd[1503]: time="2025-08-13T00:02:57.511702413Z" 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\"}" Aug 13 00:02:57.512781 containerd[1503]: time="2025-08-13T00:02:57.512743413Z" 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 567.87036ms" Aug 13 00:02:57.514147 containerd[1503]: time="2025-08-13T00:02:57.514108933Z" 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 638.91984ms" Aug 13 00:02:57.535835 containerd[1503]: time="2025-08-13T00:02:57.535781413Z" level=info msg="connecting to shim 2722ade8df8d1bb54e472d247158fe6739d9d366c20df15d4af6b1c92d12b252" address="unix:///run/containerd/s/e49c5c40663447d592ba90520e943164fbb793082315ce8374ad84c714bec00e" namespace=k8s.io protocol=ttrpc version=3 Aug 13 00:02:57.536738 containerd[1503]: time="2025-08-13T00:02:57.536569933Z" level=info msg="connecting to shim 471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a" address="unix:///run/containerd/s/62953bedd281fd5b7d07bbf72390c5241449e92bb7289df88094ed59d7ee7956" namespace=k8s.io protocol=ttrpc version=3 Aug 13 00:02:57.563155 systemd[1]: Started cri-containerd-2722ade8df8d1bb54e472d247158fe6739d9d366c20df15d4af6b1c92d12b252.scope - libcontainer container 2722ade8df8d1bb54e472d247158fe6739d9d366c20df15d4af6b1c92d12b252. Aug 13 00:02:57.566335 systemd[1]: Started cri-containerd-471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a.scope - libcontainer container 471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a. Aug 13 00:02:57.601063 containerd[1503]: time="2025-08-13T00:02:57.600971293Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-bcmnj,Uid:7ece5201-728c-4c5b-9231-7916f546732e,Namespace:kube-system,Attempt:0,} returns sandbox id \"2722ade8df8d1bb54e472d247158fe6739d9d366c20df15d4af6b1c92d12b252\"" Aug 13 00:02:57.604001 containerd[1503]: time="2025-08-13T00:02:57.603958133Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.7\"" Aug 13 00:02:57.607031 containerd[1503]: time="2025-08-13T00:02:57.606982533Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-59sd4,Uid:ae286166-c3b1-45f1-b138-958c106c9592,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a\"" Aug 13 00:02:58.492022 kubelet[1781]: E0813 00:02:58.491959 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:02:58.771672 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1534715056.mount: Deactivated successfully. Aug 13 00:02:59.040497 containerd[1503]: time="2025-08-13T00:02:59.040359773Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:02:59.041370 containerd[1503]: time="2025-08-13T00:02:59.041310053Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.7: active requests=0, bytes read=27378407" Aug 13 00:02:59.043734 containerd[1503]: time="2025-08-13T00:02:59.043645133Z" level=info msg="ImageCreate event name:\"sha256:176e5fd5af03be683be55601db94020ad4cc275f4cca27999608d3cf65c9fb11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:02:59.047716 containerd[1503]: time="2025-08-13T00:02:59.047020813Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:8d589a18b5424f77a784ef2f00feffac0ef210414100822f1c120f0d7221def3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:02:59.047716 containerd[1503]: time="2025-08-13T00:02:59.047374733Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.7\" with image id \"sha256:176e5fd5af03be683be55601db94020ad4cc275f4cca27999608d3cf65c9fb11\", repo tag \"registry.k8s.io/kube-proxy:v1.32.7\", repo digest \"registry.k8s.io/kube-proxy@sha256:8d589a18b5424f77a784ef2f00feffac0ef210414100822f1c120f0d7221def3\", size \"27377424\" in 1.443213s" Aug 13 00:02:59.047716 containerd[1503]: time="2025-08-13T00:02:59.047421213Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.7\" returns image reference \"sha256:176e5fd5af03be683be55601db94020ad4cc275f4cca27999608d3cf65c9fb11\"" Aug 13 00:02:59.048635 containerd[1503]: time="2025-08-13T00:02:59.048575693Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Aug 13 00:02:59.050515 containerd[1503]: time="2025-08-13T00:02:59.050465773Z" level=info msg="CreateContainer within sandbox \"2722ade8df8d1bb54e472d247158fe6739d9d366c20df15d4af6b1c92d12b252\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Aug 13 00:02:59.059569 containerd[1503]: time="2025-08-13T00:02:59.059526053Z" level=info msg="Container 7d152b440e33c8cde0373487ac88b42dc7e3c48585e293749037160e468e521c: CDI devices from CRI Config.CDIDevices: []" Aug 13 00:02:59.071162 containerd[1503]: time="2025-08-13T00:02:59.071110853Z" level=info msg="CreateContainer within sandbox \"2722ade8df8d1bb54e472d247158fe6739d9d366c20df15d4af6b1c92d12b252\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"7d152b440e33c8cde0373487ac88b42dc7e3c48585e293749037160e468e521c\"" Aug 13 00:02:59.071789 containerd[1503]: time="2025-08-13T00:02:59.071756373Z" level=info msg="StartContainer for \"7d152b440e33c8cde0373487ac88b42dc7e3c48585e293749037160e468e521c\"" Aug 13 00:02:59.073654 containerd[1503]: time="2025-08-13T00:02:59.073617613Z" level=info msg="connecting to shim 7d152b440e33c8cde0373487ac88b42dc7e3c48585e293749037160e468e521c" address="unix:///run/containerd/s/e49c5c40663447d592ba90520e943164fbb793082315ce8374ad84c714bec00e" protocol=ttrpc version=3 Aug 13 00:02:59.100108 systemd[1]: Started cri-containerd-7d152b440e33c8cde0373487ac88b42dc7e3c48585e293749037160e468e521c.scope - libcontainer container 7d152b440e33c8cde0373487ac88b42dc7e3c48585e293749037160e468e521c. Aug 13 00:02:59.141642 containerd[1503]: time="2025-08-13T00:02:59.141580333Z" level=info msg="StartContainer for \"7d152b440e33c8cde0373487ac88b42dc7e3c48585e293749037160e468e521c\" returns successfully" Aug 13 00:02:59.492941 kubelet[1781]: E0813 00:02:59.492795 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:00.164609 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1398394034.mount: Deactivated successfully. Aug 13 00:03:00.204799 containerd[1503]: time="2025-08-13T00:03:00.204031573Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:00.205916 containerd[1503]: time="2025-08-13T00:03:00.205861933Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673531" Aug 13 00:03:00.207989 containerd[1503]: time="2025-08-13T00:03:00.207930133Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:00.210464 containerd[1503]: time="2025-08-13T00:03:00.210425453Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:00.211224 containerd[1503]: time="2025-08-13T00:03:00.211188893Z" 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.16254708s" Aug 13 00:03:00.211283 containerd[1503]: time="2025-08-13T00:03:00.211227333Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Aug 13 00:03:00.213230 containerd[1503]: time="2025-08-13T00:03:00.213196773Z" level=info msg="CreateContainer within sandbox \"471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Aug 13 00:03:00.227922 containerd[1503]: time="2025-08-13T00:03:00.227504213Z" level=info msg="Container 5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b: CDI devices from CRI Config.CDIDevices: []" Aug 13 00:03:00.262785 containerd[1503]: time="2025-08-13T00:03:00.262722213Z" level=info msg="CreateContainer within sandbox \"471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b\"" Aug 13 00:03:00.263513 containerd[1503]: time="2025-08-13T00:03:00.263473213Z" level=info msg="StartContainer for \"5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b\"" Aug 13 00:03:00.264463 containerd[1503]: time="2025-08-13T00:03:00.264430133Z" level=info msg="connecting to shim 5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b" address="unix:///run/containerd/s/62953bedd281fd5b7d07bbf72390c5241449e92bb7289df88094ed59d7ee7956" protocol=ttrpc version=3 Aug 13 00:03:00.297099 systemd[1]: Started cri-containerd-5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b.scope - libcontainer container 5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b. Aug 13 00:03:00.336371 containerd[1503]: time="2025-08-13T00:03:00.336329413Z" level=info msg="StartContainer for \"5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b\" returns successfully" Aug 13 00:03:00.350929 systemd[1]: cri-containerd-5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b.scope: Deactivated successfully. Aug 13 00:03:00.353242 containerd[1503]: time="2025-08-13T00:03:00.352952973Z" level=info msg="received exit event container_id:\"5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b\" id:\"5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b\" pid:2110 exited_at:{seconds:1755043380 nanos:352562013}" Aug 13 00:03:00.353242 containerd[1503]: time="2025-08-13T00:03:00.353211453Z" level=info msg="TaskExit event in podsandbox handler container_id:\"5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b\" id:\"5bc0ecf7d7e8a06c975cddab0a39a4ca66dd8123dc6739b1967a4c5fa8f2d61b\" pid:2110 exited_at:{seconds:1755043380 nanos:352562013}" Aug 13 00:03:00.493460 kubelet[1781]: E0813 00:03:00.493336 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:00.958746 containerd[1503]: time="2025-08-13T00:03:00.958162453Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Aug 13 00:03:00.970381 kubelet[1781]: I0813 00:03:00.970283 1781 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-bcmnj" podStartSLOduration=4.524992453 podStartE2EDuration="5.970265013s" podCreationTimestamp="2025-08-13 00:02:55 +0000 UTC" firstStartedPulling="2025-08-13 00:02:57.603116413 +0000 UTC m=+3.686369801" lastFinishedPulling="2025-08-13 00:02:59.048388973 +0000 UTC m=+5.131642361" observedRunningTime="2025-08-13 00:02:59.959943933 +0000 UTC m=+6.043197321" watchObservedRunningTime="2025-08-13 00:03:00.970265013 +0000 UTC m=+7.053518401" Aug 13 00:03:01.494038 kubelet[1781]: E0813 00:03:01.493967 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:02.259136 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4070486476.mount: Deactivated successfully. Aug 13 00:03:02.495014 kubelet[1781]: E0813 00:03:02.494978 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:02.801110 containerd[1503]: time="2025-08-13T00:03:02.800966453Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:02.801988 containerd[1503]: time="2025-08-13T00:03:02.801941893Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Aug 13 00:03:02.803690 containerd[1503]: time="2025-08-13T00:03:02.803350773Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:02.805924 containerd[1503]: time="2025-08-13T00:03:02.805865453Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:02.806853 containerd[1503]: time="2025-08-13T00:03:02.806822133Z" 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.8486108s" Aug 13 00:03:02.807441 containerd[1503]: time="2025-08-13T00:03:02.807412373Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Aug 13 00:03:02.809389 containerd[1503]: time="2025-08-13T00:03:02.809361613Z" level=info msg="CreateContainer within sandbox \"471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Aug 13 00:03:02.816920 containerd[1503]: time="2025-08-13T00:03:02.816633573Z" level=info msg="Container 8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f: CDI devices from CRI Config.CDIDevices: []" Aug 13 00:03:02.827460 containerd[1503]: time="2025-08-13T00:03:02.827412373Z" level=info msg="CreateContainer within sandbox \"471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f\"" Aug 13 00:03:02.828182 containerd[1503]: time="2025-08-13T00:03:02.828146853Z" level=info msg="StartContainer for \"8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f\"" Aug 13 00:03:02.829100 containerd[1503]: time="2025-08-13T00:03:02.829041773Z" level=info msg="connecting to shim 8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f" address="unix:///run/containerd/s/62953bedd281fd5b7d07bbf72390c5241449e92bb7289df88094ed59d7ee7956" protocol=ttrpc version=3 Aug 13 00:03:02.856125 systemd[1]: Started cri-containerd-8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f.scope - libcontainer container 8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f. Aug 13 00:03:02.893611 containerd[1503]: time="2025-08-13T00:03:02.893564173Z" level=info msg="StartContainer for \"8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f\" returns successfully" Aug 13 00:03:02.912296 systemd[1]: cri-containerd-8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f.scope: Deactivated successfully. Aug 13 00:03:02.914831 containerd[1503]: time="2025-08-13T00:03:02.914788933Z" level=info msg="received exit event container_id:\"8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f\" id:\"8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f\" pid:2187 exited_at:{seconds:1755043382 nanos:914578653}" Aug 13 00:03:02.915076 containerd[1503]: time="2025-08-13T00:03:02.914954293Z" level=info msg="TaskExit event in podsandbox handler container_id:\"8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f\" id:\"8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f\" pid:2187 exited_at:{seconds:1755043382 nanos:914578653}" Aug 13 00:03:02.959934 kubelet[1781]: I0813 00:03:02.959881 1781 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Aug 13 00:03:03.168294 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-8d5500b5b9b479ff104fb4c922da7796e1e14fd7b10e1e1ce44b0ec447ed3b7f-rootfs.mount: Deactivated successfully. Aug 13 00:03:03.495597 kubelet[1781]: E0813 00:03:03.495471 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:03.968990 containerd[1503]: time="2025-08-13T00:03:03.968500853Z" level=info msg="CreateContainer within sandbox \"471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Aug 13 00:03:04.019931 containerd[1503]: time="2025-08-13T00:03:04.018998213Z" level=info msg="Container 93e77bb7659338208cd83e5cf2bcd21a50431517f55d489c10fa384895a36459: CDI devices from CRI Config.CDIDevices: []" Aug 13 00:03:04.029272 containerd[1503]: time="2025-08-13T00:03:04.029224173Z" level=info msg="CreateContainer within sandbox \"471526d0717ae0764699a8a6e793e1e1987c69fd96ea61c4bf45658e8e82e88a\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"93e77bb7659338208cd83e5cf2bcd21a50431517f55d489c10fa384895a36459\"" Aug 13 00:03:04.030010 containerd[1503]: time="2025-08-13T00:03:04.029978493Z" level=info msg="StartContainer for \"93e77bb7659338208cd83e5cf2bcd21a50431517f55d489c10fa384895a36459\"" Aug 13 00:03:04.030782 containerd[1503]: time="2025-08-13T00:03:04.030742533Z" level=info msg="connecting to shim 93e77bb7659338208cd83e5cf2bcd21a50431517f55d489c10fa384895a36459" address="unix:///run/containerd/s/62953bedd281fd5b7d07bbf72390c5241449e92bb7289df88094ed59d7ee7956" protocol=ttrpc version=3 Aug 13 00:03:04.057112 systemd[1]: Started cri-containerd-93e77bb7659338208cd83e5cf2bcd21a50431517f55d489c10fa384895a36459.scope - libcontainer container 93e77bb7659338208cd83e5cf2bcd21a50431517f55d489c10fa384895a36459. Aug 13 00:03:04.090970 containerd[1503]: time="2025-08-13T00:03:04.090926253Z" level=info msg="StartContainer for \"93e77bb7659338208cd83e5cf2bcd21a50431517f55d489c10fa384895a36459\" returns successfully" Aug 13 00:03:04.496241 kubelet[1781]: E0813 00:03:04.496193 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:04.987960 kubelet[1781]: I0813 00:03:04.987821 1781 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-59sd4" podStartSLOduration=4.787714013 podStartE2EDuration="9.987805413s" podCreationTimestamp="2025-08-13 00:02:55 +0000 UTC" firstStartedPulling="2025-08-13 00:02:57.608090973 +0000 UTC m=+3.691344361" lastFinishedPulling="2025-08-13 00:03:02.808182413 +0000 UTC m=+8.891435761" observedRunningTime="2025-08-13 00:03:04.987178493 +0000 UTC m=+11.070431961" watchObservedRunningTime="2025-08-13 00:03:04.987805413 +0000 UTC m=+11.071058801" Aug 13 00:03:05.183450 systemd-networkd[1401]: flannel.1: Link UP Aug 13 00:03:05.183461 systemd-networkd[1401]: flannel.1: Gained carrier Aug 13 00:03:05.496964 kubelet[1781]: E0813 00:03:05.496924 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:06.480023 systemd-networkd[1401]: flannel.1: Gained IPv6LL Aug 13 00:03:06.481538 systemd[1]: Created slice kubepods-besteffort-pode2fb7366_7178_4ba8_9cd7_26ea19d58513.slice - libcontainer container kubepods-besteffort-pode2fb7366_7178_4ba8_9cd7_26ea19d58513.slice. Aug 13 00:03:06.492362 kubelet[1781]: I0813 00:03:06.492311 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dp7h6\" (UniqueName: \"kubernetes.io/projected/e2fb7366-7178-4ba8-9cd7-26ea19d58513-kube-api-access-dp7h6\") pod \"nginx-deployment-7fcdb87857-hhj8s\" (UID: \"e2fb7366-7178-4ba8-9cd7-26ea19d58513\") " pod="default/nginx-deployment-7fcdb87857-hhj8s" Aug 13 00:03:06.497537 kubelet[1781]: E0813 00:03:06.497481 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:06.785007 containerd[1503]: time="2025-08-13T00:03:06.784873933Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-hhj8s,Uid:e2fb7366-7178-4ba8-9cd7-26ea19d58513,Namespace:default,Attempt:0,}" Aug 13 00:03:06.812985 systemd-networkd[1401]: cni0: Link UP Aug 13 00:03:06.812991 systemd-networkd[1401]: cni0: Gained carrier Aug 13 00:03:06.813239 systemd-networkd[1401]: cni0: Lost carrier Aug 13 00:03:06.819775 systemd-networkd[1401]: veth58a73b17: Link UP Aug 13 00:03:06.823045 kernel: cni0: port 1(veth58a73b17) entered blocking state Aug 13 00:03:06.823251 kernel: cni0: port 1(veth58a73b17) entered disabled state Aug 13 00:03:06.824052 kernel: veth58a73b17: entered allmulticast mode Aug 13 00:03:06.824938 kernel: veth58a73b17: entered promiscuous mode Aug 13 00:03:06.839439 kernel: cni0: port 1(veth58a73b17) entered blocking state Aug 13 00:03:06.839606 kernel: cni0: port 1(veth58a73b17) entered forwarding state Aug 13 00:03:06.839670 systemd-networkd[1401]: veth58a73b17: Gained carrier Aug 13 00:03:06.840123 systemd-networkd[1401]: cni0: Gained carrier Aug 13 00:03:06.842687 containerd[1503]: 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)(0x400001c938), "name":"cbr0", "type":"bridge"} Aug 13 00:03:06.842687 containerd[1503]: delegateAdd: netconf sent to delegate plugin: Aug 13 00:03:06.892285 containerd[1503]: {"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-08-13T00:03:06.892222773Z" level=info msg="connecting to shim 62082c29088e64a589a8909873ffc9640f245301c02779e87f2b7a2edd43a299" address="unix:///run/containerd/s/d83e5aea66903babdbae66f2e92c807510b0d0b6f011a75d48ac45c3c063f8fb" namespace=k8s.io protocol=ttrpc version=3 Aug 13 00:03:06.933138 systemd[1]: Started cri-containerd-62082c29088e64a589a8909873ffc9640f245301c02779e87f2b7a2edd43a299.scope - libcontainer container 62082c29088e64a589a8909873ffc9640f245301c02779e87f2b7a2edd43a299. Aug 13 00:03:06.947881 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 13 00:03:06.976355 containerd[1503]: time="2025-08-13T00:03:06.976310893Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-hhj8s,Uid:e2fb7366-7178-4ba8-9cd7-26ea19d58513,Namespace:default,Attempt:0,} returns sandbox id \"62082c29088e64a589a8909873ffc9640f245301c02779e87f2b7a2edd43a299\"" Aug 13 00:03:06.977552 containerd[1503]: time="2025-08-13T00:03:06.977512653Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 13 00:03:07.497702 kubelet[1781]: E0813 00:03:07.497632 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:07.951129 systemd-networkd[1401]: cni0: Gained IPv6LL Aug 13 00:03:08.399055 systemd-networkd[1401]: veth58a73b17: Gained IPv6LL Aug 13 00:03:08.498245 kubelet[1781]: E0813 00:03:08.498188 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:08.917350 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2852296889.mount: Deactivated successfully. Aug 13 00:03:09.499340 kubelet[1781]: E0813 00:03:09.499298 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:09.664836 containerd[1503]: time="2025-08-13T00:03:09.664771013Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:09.668077 containerd[1503]: time="2025-08-13T00:03:09.668028573Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69963857" Aug 13 00:03:09.669140 containerd[1503]: time="2025-08-13T00:03:09.669111893Z" level=info msg="ImageCreate event name:\"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:09.671995 containerd[1503]: time="2025-08-13T00:03:09.671470573Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:09.672520 containerd[1503]: time="2025-08-13T00:03:09.672486093Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\", size \"69963735\" in 2.69492648s" Aug 13 00:03:09.672559 containerd[1503]: time="2025-08-13T00:03:09.672528933Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\"" Aug 13 00:03:09.674440 containerd[1503]: time="2025-08-13T00:03:09.674414453Z" level=info msg="CreateContainer within sandbox \"62082c29088e64a589a8909873ffc9640f245301c02779e87f2b7a2edd43a299\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Aug 13 00:03:09.681163 containerd[1503]: time="2025-08-13T00:03:09.681123133Z" level=info msg="Container 1f9fe0e0ef6cca215da69c79d4740beae23a8b8fe19a93ff8e63e34ce97097a4: CDI devices from CRI Config.CDIDevices: []" Aug 13 00:03:09.686125 containerd[1503]: time="2025-08-13T00:03:09.686089013Z" level=info msg="CreateContainer within sandbox \"62082c29088e64a589a8909873ffc9640f245301c02779e87f2b7a2edd43a299\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"1f9fe0e0ef6cca215da69c79d4740beae23a8b8fe19a93ff8e63e34ce97097a4\"" Aug 13 00:03:09.687598 containerd[1503]: time="2025-08-13T00:03:09.686597693Z" level=info msg="StartContainer for \"1f9fe0e0ef6cca215da69c79d4740beae23a8b8fe19a93ff8e63e34ce97097a4\"" Aug 13 00:03:09.687598 containerd[1503]: time="2025-08-13T00:03:09.687367413Z" level=info msg="connecting to shim 1f9fe0e0ef6cca215da69c79d4740beae23a8b8fe19a93ff8e63e34ce97097a4" address="unix:///run/containerd/s/d83e5aea66903babdbae66f2e92c807510b0d0b6f011a75d48ac45c3c063f8fb" protocol=ttrpc version=3 Aug 13 00:03:09.709117 systemd[1]: Started cri-containerd-1f9fe0e0ef6cca215da69c79d4740beae23a8b8fe19a93ff8e63e34ce97097a4.scope - libcontainer container 1f9fe0e0ef6cca215da69c79d4740beae23a8b8fe19a93ff8e63e34ce97097a4. Aug 13 00:03:09.742275 containerd[1503]: time="2025-08-13T00:03:09.742235933Z" level=info msg="StartContainer for \"1f9fe0e0ef6cca215da69c79d4740beae23a8b8fe19a93ff8e63e34ce97097a4\" returns successfully" Aug 13 00:03:10.499864 kubelet[1781]: E0813 00:03:10.499811 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:11.499980 kubelet[1781]: E0813 00:03:11.499921 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:12.503014 kubelet[1781]: E0813 00:03:12.500798 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:13.089683 kubelet[1781]: I0813 00:03:13.089549 1781 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-hhj8s" podStartSLOduration=4.393411733 podStartE2EDuration="7.089530813s" podCreationTimestamp="2025-08-13 00:03:06 +0000 UTC" firstStartedPulling="2025-08-13 00:03:06.977115133 +0000 UTC m=+13.060368521" lastFinishedPulling="2025-08-13 00:03:09.673234253 +0000 UTC m=+15.756487601" observedRunningTime="2025-08-13 00:03:09.989944773 +0000 UTC m=+16.073198121" watchObservedRunningTime="2025-08-13 00:03:13.089530813 +0000 UTC m=+19.172784201" Aug 13 00:03:13.097859 systemd[1]: Created slice kubepods-besteffort-pod1ec5591b_5364_468b_a5a6_6cea929d6195.slice - libcontainer container kubepods-besteffort-pod1ec5591b_5364_468b_a5a6_6cea929d6195.slice. Aug 13 00:03:13.126699 kubelet[1781]: I0813 00:03:13.126647 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1ec5591b-5364-468b-a5a6-6cea929d6195-data\") pod \"nfs-server-provisioner-0\" (UID: \"1ec5591b-5364-468b-a5a6-6cea929d6195\") " pod="default/nfs-server-provisioner-0" Aug 13 00:03:13.126699 kubelet[1781]: I0813 00:03:13.126694 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9sjrp\" (UniqueName: \"kubernetes.io/projected/1ec5591b-5364-468b-a5a6-6cea929d6195-kube-api-access-9sjrp\") pod \"nfs-server-provisioner-0\" (UID: \"1ec5591b-5364-468b-a5a6-6cea929d6195\") " pod="default/nfs-server-provisioner-0" Aug 13 00:03:13.401857 containerd[1503]: time="2025-08-13T00:03:13.401675773Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1ec5591b-5364-468b-a5a6-6cea929d6195,Namespace:default,Attempt:0,}" Aug 13 00:03:13.418847 systemd-networkd[1401]: vethbafa5c14: Link UP Aug 13 00:03:13.423209 kernel: cni0: port 2(vethbafa5c14) entered blocking state Aug 13 00:03:13.423300 kernel: cni0: port 2(vethbafa5c14) entered disabled state Aug 13 00:03:13.423316 kernel: vethbafa5c14: entered allmulticast mode Aug 13 00:03:13.423332 kernel: vethbafa5c14: entered promiscuous mode Aug 13 00:03:13.431618 kernel: cni0: port 2(vethbafa5c14) entered blocking state Aug 13 00:03:13.431720 kernel: cni0: port 2(vethbafa5c14) entered forwarding state Aug 13 00:03:13.431739 systemd-networkd[1401]: vethbafa5c14: Gained carrier Aug 13 00:03:13.436147 containerd[1503]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000012938), "name":"cbr0", "type":"bridge"} Aug 13 00:03:13.436147 containerd[1503]: delegateAdd: netconf sent to delegate plugin: Aug 13 00:03:13.460525 containerd[1503]: {"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-08-13T00:03:13.460457933Z" level=info msg="connecting to shim 69b0d419898b406efe3ddf902833f27bc5c51b5916e6b2d664b19ea0b01a1d79" address="unix:///run/containerd/s/2c8fdbeea9da5a26bd569378a2443629d3cf7aea679332764e48d9fbe931c0e8" namespace=k8s.io protocol=ttrpc version=3 Aug 13 00:03:13.489136 systemd[1]: Started cri-containerd-69b0d419898b406efe3ddf902833f27bc5c51b5916e6b2d664b19ea0b01a1d79.scope - libcontainer container 69b0d419898b406efe3ddf902833f27bc5c51b5916e6b2d664b19ea0b01a1d79. Aug 13 00:03:13.500309 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 13 00:03:13.501422 kubelet[1781]: E0813 00:03:13.501138 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:13.520725 containerd[1503]: time="2025-08-13T00:03:13.520682773Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1ec5591b-5364-468b-a5a6-6cea929d6195,Namespace:default,Attempt:0,} returns sandbox id \"69b0d419898b406efe3ddf902833f27bc5c51b5916e6b2d664b19ea0b01a1d79\"" Aug 13 00:03:13.522472 containerd[1503]: time="2025-08-13T00:03:13.522445213Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Aug 13 00:03:14.490688 kubelet[1781]: E0813 00:03:14.490640 1781 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:14.502000 kubelet[1781]: E0813 00:03:14.501966 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:15.163669 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2292083983.mount: Deactivated successfully. Aug 13 00:03:15.183118 systemd-networkd[1401]: vethbafa5c14: Gained IPv6LL Aug 13 00:03:15.502577 kubelet[1781]: E0813 00:03:15.502476 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:16.437077 containerd[1503]: time="2025-08-13T00:03:16.437025333Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:16.437820 containerd[1503]: time="2025-08-13T00:03:16.437783373Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Aug 13 00:03:16.438844 containerd[1503]: time="2025-08-13T00:03:16.438811133Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:16.442928 containerd[1503]: time="2025-08-13T00:03:16.442618573Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:16.443258 containerd[1503]: time="2025-08-13T00:03:16.443229413Z" 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 2.92074772s" Aug 13 00:03:16.443332 containerd[1503]: time="2025-08-13T00:03:16.443317013Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Aug 13 00:03:16.446043 containerd[1503]: time="2025-08-13T00:03:16.445176053Z" level=info msg="CreateContainer within sandbox \"69b0d419898b406efe3ddf902833f27bc5c51b5916e6b2d664b19ea0b01a1d79\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Aug 13 00:03:16.456470 containerd[1503]: time="2025-08-13T00:03:16.456337253Z" level=info msg="Container 500baf8b4c792298c2f2e728d914a4373e1de634a26a9cd6e7789dcf68acc83c: CDI devices from CRI Config.CDIDevices: []" Aug 13 00:03:16.463644 containerd[1503]: time="2025-08-13T00:03:16.463578213Z" level=info msg="CreateContainer within sandbox \"69b0d419898b406efe3ddf902833f27bc5c51b5916e6b2d664b19ea0b01a1d79\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"500baf8b4c792298c2f2e728d914a4373e1de634a26a9cd6e7789dcf68acc83c\"" Aug 13 00:03:16.464128 containerd[1503]: time="2025-08-13T00:03:16.464100133Z" level=info msg="StartContainer for \"500baf8b4c792298c2f2e728d914a4373e1de634a26a9cd6e7789dcf68acc83c\"" Aug 13 00:03:16.465132 containerd[1503]: time="2025-08-13T00:03:16.465097973Z" level=info msg="connecting to shim 500baf8b4c792298c2f2e728d914a4373e1de634a26a9cd6e7789dcf68acc83c" address="unix:///run/containerd/s/2c8fdbeea9da5a26bd569378a2443629d3cf7aea679332764e48d9fbe931c0e8" protocol=ttrpc version=3 Aug 13 00:03:16.485076 systemd[1]: Started cri-containerd-500baf8b4c792298c2f2e728d914a4373e1de634a26a9cd6e7789dcf68acc83c.scope - libcontainer container 500baf8b4c792298c2f2e728d914a4373e1de634a26a9cd6e7789dcf68acc83c. Aug 13 00:03:16.503044 kubelet[1781]: E0813 00:03:16.502999 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:16.516890 containerd[1503]: time="2025-08-13T00:03:16.516854813Z" level=info msg="StartContainer for \"500baf8b4c792298c2f2e728d914a4373e1de634a26a9cd6e7789dcf68acc83c\" returns successfully" Aug 13 00:03:17.007586 kubelet[1781]: I0813 00:03:17.007507 1781 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.085639573 podStartE2EDuration="4.007486453s" podCreationTimestamp="2025-08-13 00:03:13 +0000 UTC" firstStartedPulling="2025-08-13 00:03:13.522202973 +0000 UTC m=+19.605456361" lastFinishedPulling="2025-08-13 00:03:16.444049853 +0000 UTC m=+22.527303241" observedRunningTime="2025-08-13 00:03:17.007294413 +0000 UTC m=+23.090547801" watchObservedRunningTime="2025-08-13 00:03:17.007486453 +0000 UTC m=+23.090739881" Aug 13 00:03:17.504081 kubelet[1781]: E0813 00:03:17.504031 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:18.505151 kubelet[1781]: E0813 00:03:18.505039 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:19.505372 kubelet[1781]: E0813 00:03:19.505313 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:20.505760 kubelet[1781]: E0813 00:03:20.505703 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:21.507283 kubelet[1781]: E0813 00:03:21.507224 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:22.508002 kubelet[1781]: E0813 00:03:22.507953 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:23.508851 kubelet[1781]: E0813 00:03:23.508769 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:24.509706 kubelet[1781]: E0813 00:03:24.509655 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:25.510847 kubelet[1781]: E0813 00:03:25.510790 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:25.898193 systemd[1]: Created slice kubepods-besteffort-pod2c67836e_08e9_4cd5_80c4_2cd8c69d7e0f.slice - libcontainer container kubepods-besteffort-pod2c67836e_08e9_4cd5_80c4_2cd8c69d7e0f.slice. Aug 13 00:03:26.008152 kubelet[1781]: I0813 00:03:26.008088 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9w2wt\" (UniqueName: \"kubernetes.io/projected/2c67836e-08e9-4cd5-80c4-2cd8c69d7e0f-kube-api-access-9w2wt\") pod \"test-pod-1\" (UID: \"2c67836e-08e9-4cd5-80c4-2cd8c69d7e0f\") " pod="default/test-pod-1" Aug 13 00:03:26.008152 kubelet[1781]: I0813 00:03:26.008150 1781 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-9a24d434-d7fc-4297-9428-1b6ba19e2afb\" (UniqueName: \"kubernetes.io/nfs/2c67836e-08e9-4cd5-80c4-2cd8c69d7e0f-pvc-9a24d434-d7fc-4297-9428-1b6ba19e2afb\") pod \"test-pod-1\" (UID: \"2c67836e-08e9-4cd5-80c4-2cd8c69d7e0f\") " pod="default/test-pod-1" Aug 13 00:03:26.153084 kernel: netfs: FS-Cache loaded Aug 13 00:03:26.196044 kernel: RPC: Registered named UNIX socket transport module. Aug 13 00:03:26.196168 kernel: RPC: Registered udp transport module. Aug 13 00:03:26.196192 kernel: RPC: Registered tcp transport module. Aug 13 00:03:26.197293 kernel: RPC: Registered tcp-with-tls transport module. Aug 13 00:03:26.197407 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 13 00:03:26.384143 kernel: NFS: Registering the id_resolver key type Aug 13 00:03:26.384247 kernel: Key type id_resolver registered Aug 13 00:03:26.384266 kernel: Key type id_legacy registered Aug 13 00:03:26.408268 nfsidmap[2793]: 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 Aug 13 00:03:26.409788 nfsidmap[2793]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 13 00:03:26.411648 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 Aug 13 00:03:26.411784 nfsidmap[2794]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 13 00:03:26.418869 nfsrahead[2796]: setting /var/lib/kubelet/pods/2c67836e-08e9-4cd5-80c4-2cd8c69d7e0f/volumes/kubernetes.io~nfs/pvc-9a24d434-d7fc-4297-9428-1b6ba19e2afb readahead to 128 Aug 13 00:03:26.502655 containerd[1503]: time="2025-08-13T00:03:26.502541773Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2c67836e-08e9-4cd5-80c4-2cd8c69d7e0f,Namespace:default,Attempt:0,}" Aug 13 00:03:26.511333 kubelet[1781]: E0813 00:03:26.511296 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:26.512388 kernel: cni0: port 3(veth3caea0c7) entered blocking state Aug 13 00:03:26.512459 kernel: cni0: port 3(veth3caea0c7) entered disabled state Aug 13 00:03:26.513349 kernel: veth3caea0c7: entered allmulticast mode Aug 13 00:03:26.514412 kernel: veth3caea0c7: entered promiscuous mode Aug 13 00:03:26.515261 systemd-networkd[1401]: veth3caea0c7: Link UP Aug 13 00:03:26.522160 kernel: cni0: port 3(veth3caea0c7) entered blocking state Aug 13 00:03:26.522207 kernel: cni0: port 3(veth3caea0c7) entered forwarding state Aug 13 00:03:26.522350 systemd-networkd[1401]: veth3caea0c7: Gained carrier Aug 13 00:03:26.526150 containerd[1503]: 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"} Aug 13 00:03:26.526150 containerd[1503]: delegateAdd: netconf sent to delegate plugin: Aug 13 00:03:26.556792 containerd[1503]: {"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-08-13T00:03:26.556733918Z" level=info msg="connecting to shim 38f08451e4f12dca2a1f4d382423263755668bf4fa4b8ec6bb991cbf41645586" address="unix:///run/containerd/s/ac9e439897dc88f756f26046d266441f7e5dcca143dddc1a4ba6f9ee5edbbb41" namespace=k8s.io protocol=ttrpc version=3 Aug 13 00:03:26.586101 systemd[1]: Started cri-containerd-38f08451e4f12dca2a1f4d382423263755668bf4fa4b8ec6bb991cbf41645586.scope - libcontainer container 38f08451e4f12dca2a1f4d382423263755668bf4fa4b8ec6bb991cbf41645586. Aug 13 00:03:26.597999 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 13 00:03:26.619731 containerd[1503]: time="2025-08-13T00:03:26.619668679Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2c67836e-08e9-4cd5-80c4-2cd8c69d7e0f,Namespace:default,Attempt:0,} returns sandbox id \"38f08451e4f12dca2a1f4d382423263755668bf4fa4b8ec6bb991cbf41645586\"" Aug 13 00:03:26.620837 containerd[1503]: time="2025-08-13T00:03:26.620797194Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 13 00:03:26.931845 containerd[1503]: time="2025-08-13T00:03:26.931756185Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:03:26.932563 containerd[1503]: time="2025-08-13T00:03:26.932534834Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Aug 13 00:03:26.935216 containerd[1503]: time="2025-08-13T00:03:26.935182970Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\", size \"69963735\" in 314.348178ms" Aug 13 00:03:26.935216 containerd[1503]: time="2025-08-13T00:03:26.935214409Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\"" Aug 13 00:03:26.937637 containerd[1503]: time="2025-08-13T00:03:26.937608995Z" level=info msg="CreateContainer within sandbox \"38f08451e4f12dca2a1f4d382423263755668bf4fa4b8ec6bb991cbf41645586\" for container &ContainerMetadata{Name:test,Attempt:0,}" Aug 13 00:03:26.945457 containerd[1503]: time="2025-08-13T00:03:26.945403727Z" level=info msg="Container 8ace34c374256a6d692dc84224115069db9ac82055c9069dcd9d06bc889763a8: CDI devices from CRI Config.CDIDevices: []" Aug 13 00:03:26.952317 containerd[1503]: time="2025-08-13T00:03:26.952260977Z" level=info msg="CreateContainer within sandbox \"38f08451e4f12dca2a1f4d382423263755668bf4fa4b8ec6bb991cbf41645586\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"8ace34c374256a6d692dc84224115069db9ac82055c9069dcd9d06bc889763a8\"" Aug 13 00:03:26.954006 containerd[1503]: time="2025-08-13T00:03:26.953967870Z" level=info msg="StartContainer for \"8ace34c374256a6d692dc84224115069db9ac82055c9069dcd9d06bc889763a8\"" Aug 13 00:03:26.954940 containerd[1503]: time="2025-08-13T00:03:26.954882354Z" level=info msg="connecting to shim 8ace34c374256a6d692dc84224115069db9ac82055c9069dcd9d06bc889763a8" address="unix:///run/containerd/s/ac9e439897dc88f756f26046d266441f7e5dcca143dddc1a4ba6f9ee5edbbb41" protocol=ttrpc version=3 Aug 13 00:03:26.977134 systemd[1]: Started cri-containerd-8ace34c374256a6d692dc84224115069db9ac82055c9069dcd9d06bc889763a8.scope - libcontainer container 8ace34c374256a6d692dc84224115069db9ac82055c9069dcd9d06bc889763a8. Aug 13 00:03:27.018514 containerd[1503]: time="2025-08-13T00:03:27.017737520Z" level=info msg="StartContainer for \"8ace34c374256a6d692dc84224115069db9ac82055c9069dcd9d06bc889763a8\" returns successfully" Aug 13 00:03:27.512426 kubelet[1781]: E0813 00:03:27.512357 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:28.495187 systemd-networkd[1401]: veth3caea0c7: Gained IPv6LL Aug 13 00:03:28.512580 kubelet[1781]: E0813 00:03:28.512525 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:29.513626 kubelet[1781]: E0813 00:03:29.513572 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:30.514986 kubelet[1781]: E0813 00:03:30.514706 1781 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:03:30.731768 update_engine[1485]: I20250813 00:03:30.731686 1485 update_attempter.cc:509] Updating boot flags...