May 13 23:42:50.912839 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 13 23:42:50.912864 kernel: Linux version 6.6.89-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 May 13 22:16:18 -00 2025 May 13 23:42:50.912874 kernel: KASLR enabled May 13 23:42:50.912880 kernel: efi: EFI v2.7 by EDK II May 13 23:42:50.912886 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbae018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40218 May 13 23:42:50.912892 kernel: random: crng init done May 13 23:42:50.912899 kernel: secureboot: Secure boot disabled May 13 23:42:50.912905 kernel: ACPI: Early table checksum verification disabled May 13 23:42:50.912911 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) May 13 23:42:50.912919 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 13 23:42:50.912926 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912932 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912938 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912944 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912951 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912959 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912966 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912973 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912979 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 13 23:42:50.912986 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 13 23:42:50.912992 kernel: NUMA: Failed to initialise from firmware May 13 23:42:50.912998 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 13 23:42:50.913005 kernel: NUMA: NODE_DATA [mem 0xdc957800-0xdc95cfff] May 13 23:42:50.913011 kernel: Zone ranges: May 13 23:42:50.913018 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 13 23:42:50.913026 kernel: DMA32 empty May 13 23:42:50.913057 kernel: Normal empty May 13 23:42:50.913064 kernel: Movable zone start for each node May 13 23:42:50.913075 kernel: Early memory node ranges May 13 23:42:50.913083 kernel: node 0: [mem 0x0000000040000000-0x00000000d967ffff] May 13 23:42:50.913090 kernel: node 0: [mem 0x00000000d9680000-0x00000000d968ffff] May 13 23:42:50.913096 kernel: node 0: [mem 0x00000000d9690000-0x00000000d976ffff] May 13 23:42:50.913103 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] May 13 23:42:50.913109 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] May 13 23:42:50.913115 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 13 23:42:50.913122 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 13 23:42:50.913128 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 13 23:42:50.913137 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 13 23:42:50.913144 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 13 23:42:50.913150 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 13 23:42:50.913160 kernel: psci: probing for conduit method from ACPI. May 13 23:42:50.913167 kernel: psci: PSCIv1.1 detected in firmware. May 13 23:42:50.913174 kernel: psci: Using standard PSCI v0.2 function IDs May 13 23:42:50.913182 kernel: psci: Trusted OS migration not required May 13 23:42:50.913189 kernel: psci: SMC Calling Convention v1.1 May 13 23:42:50.913196 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 13 23:42:50.913203 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 May 13 23:42:50.913209 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 May 13 23:42:50.913217 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 13 23:42:50.913223 kernel: Detected PIPT I-cache on CPU0 May 13 23:42:50.913230 kernel: CPU features: detected: GIC system register CPU interface May 13 23:42:50.913237 kernel: CPU features: detected: Hardware dirty bit management May 13 23:42:50.913248 kernel: CPU features: detected: Spectre-v4 May 13 23:42:50.913257 kernel: CPU features: detected: Spectre-BHB May 13 23:42:50.913264 kernel: CPU features: kernel page table isolation forced ON by KASLR May 13 23:42:50.913271 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 13 23:42:50.913278 kernel: CPU features: detected: ARM erratum 1418040 May 13 23:42:50.913285 kernel: CPU features: detected: SSBS not fully self-synchronizing May 13 23:42:50.913292 kernel: alternatives: applying boot alternatives May 13 23:42:50.913299 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=3174b2682629aa8ad4069807ed6fd62c10f62266ee1e150a1104f2a2fb6489b5 May 13 23:42:50.913307 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 13 23:42:50.913314 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 13 23:42:50.913321 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 13 23:42:50.913328 kernel: Fallback order for Node 0: 0 May 13 23:42:50.913336 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 13 23:42:50.913343 kernel: Policy zone: DMA May 13 23:42:50.913350 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 13 23:42:50.913356 kernel: software IO TLB: area num 4. May 13 23:42:50.913363 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) May 13 23:42:50.913371 kernel: Memory: 2387344K/2572288K available (10368K kernel code, 2186K rwdata, 8100K rodata, 38464K init, 897K bss, 184944K reserved, 0K cma-reserved) May 13 23:42:50.913378 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 13 23:42:50.913385 kernel: rcu: Preemptible hierarchical RCU implementation. May 13 23:42:50.913392 kernel: rcu: RCU event tracing is enabled. May 13 23:42:50.913400 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 13 23:42:50.913406 kernel: Trampoline variant of Tasks RCU enabled. May 13 23:42:50.913413 kernel: Tracing variant of Tasks RCU enabled. May 13 23:42:50.913422 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 13 23:42:50.913429 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 13 23:42:50.913436 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 13 23:42:50.913443 kernel: GICv3: 256 SPIs implemented May 13 23:42:50.913450 kernel: GICv3: 0 Extended SPIs implemented May 13 23:42:50.913456 kernel: Root IRQ handler: gic_handle_irq May 13 23:42:50.913463 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 13 23:42:50.913471 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 13 23:42:50.913478 kernel: ITS [mem 0x08080000-0x0809ffff] May 13 23:42:50.913485 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) May 13 23:42:50.913492 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) May 13 23:42:50.913500 kernel: GICv3: using LPI property table @0x00000000400f0000 May 13 23:42:50.913507 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 May 13 23:42:50.913514 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 13 23:42:50.913521 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:42:50.913528 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 13 23:42:50.913535 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 13 23:42:50.913542 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 13 23:42:50.913550 kernel: arm-pv: using stolen time PV May 13 23:42:50.913557 kernel: Console: colour dummy device 80x25 May 13 23:42:50.913564 kernel: ACPI: Core revision 20230628 May 13 23:42:50.913572 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 13 23:42:50.913580 kernel: pid_max: default: 32768 minimum: 301 May 13 23:42:50.913588 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 13 23:42:50.913595 kernel: landlock: Up and running. May 13 23:42:50.913602 kernel: SELinux: Initializing. May 13 23:42:50.913609 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 13 23:42:50.913616 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 13 23:42:50.913623 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 13 23:42:50.913631 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 13 23:42:50.913638 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 13 23:42:50.913647 kernel: rcu: Hierarchical SRCU implementation. May 13 23:42:50.913654 kernel: rcu: Max phase no-delay instances is 400. May 13 23:42:50.913661 kernel: Platform MSI: ITS@0x8080000 domain created May 13 23:42:50.913668 kernel: PCI/MSI: ITS@0x8080000 domain created May 13 23:42:50.913675 kernel: Remapping and enabling EFI services. May 13 23:42:50.913682 kernel: smp: Bringing up secondary CPUs ... May 13 23:42:50.913689 kernel: Detected PIPT I-cache on CPU1 May 13 23:42:50.913696 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 13 23:42:50.913703 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 May 13 23:42:50.913712 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:42:50.913720 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 13 23:42:50.913732 kernel: Detected PIPT I-cache on CPU2 May 13 23:42:50.913741 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 13 23:42:50.913749 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 May 13 23:42:50.913756 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:42:50.913764 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 13 23:42:50.913771 kernel: Detected PIPT I-cache on CPU3 May 13 23:42:50.913778 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 13 23:42:50.913786 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 May 13 23:42:50.913795 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 13 23:42:50.913803 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 13 23:42:50.913810 kernel: smp: Brought up 1 node, 4 CPUs May 13 23:42:50.913817 kernel: SMP: Total of 4 processors activated. May 13 23:42:50.913825 kernel: CPU features: detected: 32-bit EL0 Support May 13 23:42:50.913832 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 13 23:42:50.913840 kernel: CPU features: detected: Common not Private translations May 13 23:42:50.913849 kernel: CPU features: detected: CRC32 instructions May 13 23:42:50.913856 kernel: CPU features: detected: Enhanced Virtualization Traps May 13 23:42:50.913864 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 13 23:42:50.913871 kernel: CPU features: detected: LSE atomic instructions May 13 23:42:50.913878 kernel: CPU features: detected: Privileged Access Never May 13 23:42:50.913886 kernel: CPU features: detected: RAS Extension Support May 13 23:42:50.913894 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 13 23:42:50.913901 kernel: CPU: All CPU(s) started at EL1 May 13 23:42:50.913908 kernel: alternatives: applying system-wide alternatives May 13 23:42:50.913918 kernel: devtmpfs: initialized May 13 23:42:50.913925 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 13 23:42:50.913933 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 13 23:42:50.913941 kernel: pinctrl core: initialized pinctrl subsystem May 13 23:42:50.913948 kernel: SMBIOS 3.0.0 present. May 13 23:42:50.913956 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 13 23:42:50.913963 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 13 23:42:50.913971 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 13 23:42:50.913978 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 13 23:42:50.913987 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 13 23:42:50.913995 kernel: audit: initializing netlink subsys (disabled) May 13 23:42:50.914003 kernel: audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 May 13 23:42:50.914010 kernel: thermal_sys: Registered thermal governor 'step_wise' May 13 23:42:50.914017 kernel: cpuidle: using governor menu May 13 23:42:50.914025 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 13 23:42:50.914039 kernel: ASID allocator initialised with 32768 entries May 13 23:42:50.914047 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 13 23:42:50.914054 kernel: Serial: AMBA PL011 UART driver May 13 23:42:50.914064 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 13 23:42:50.914076 kernel: Modules: 0 pages in range for non-PLT usage May 13 23:42:50.914084 kernel: Modules: 509232 pages in range for PLT usage May 13 23:42:50.914092 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 13 23:42:50.914099 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 13 23:42:50.914107 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 13 23:42:50.914115 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 13 23:42:50.914122 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 13 23:42:50.914130 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 13 23:42:50.914139 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 13 23:42:50.914146 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 13 23:42:50.914154 kernel: ACPI: Added _OSI(Module Device) May 13 23:42:50.914161 kernel: ACPI: Added _OSI(Processor Device) May 13 23:42:50.914169 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 13 23:42:50.914176 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 13 23:42:50.914184 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 13 23:42:50.914191 kernel: ACPI: Interpreter enabled May 13 23:42:50.914199 kernel: ACPI: Using GIC for interrupt routing May 13 23:42:50.914206 kernel: ACPI: MCFG table detected, 1 entries May 13 23:42:50.914215 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 13 23:42:50.914223 kernel: printk: console [ttyAMA0] enabled May 13 23:42:50.914230 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 13 23:42:50.914391 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 13 23:42:50.914470 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 13 23:42:50.914537 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 13 23:42:50.914603 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 13 23:42:50.914671 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 13 23:42:50.914681 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 13 23:42:50.914689 kernel: PCI host bridge to bus 0000:00 May 13 23:42:50.914762 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 13 23:42:50.914824 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 13 23:42:50.914887 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 13 23:42:50.914946 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 13 23:42:50.915091 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 13 23:42:50.915197 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 13 23:42:50.915270 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 13 23:42:50.915340 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 13 23:42:50.915410 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 13 23:42:50.915477 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 13 23:42:50.915545 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 13 23:42:50.915619 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 13 23:42:50.915684 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 13 23:42:50.915745 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 13 23:42:50.915805 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 13 23:42:50.915815 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 13 23:42:50.915823 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 13 23:42:50.915830 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 13 23:42:50.915840 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 13 23:42:50.915848 kernel: iommu: Default domain type: Translated May 13 23:42:50.915855 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 13 23:42:50.915863 kernel: efivars: Registered efivars operations May 13 23:42:50.915870 kernel: vgaarb: loaded May 13 23:42:50.915878 kernel: clocksource: Switched to clocksource arch_sys_counter May 13 23:42:50.915885 kernel: VFS: Disk quotas dquot_6.6.0 May 13 23:42:50.915894 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 13 23:42:50.915901 kernel: pnp: PnP ACPI init May 13 23:42:50.915976 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 13 23:42:50.915987 kernel: pnp: PnP ACPI: found 1 devices May 13 23:42:50.915995 kernel: NET: Registered PF_INET protocol family May 13 23:42:50.916003 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 13 23:42:50.916010 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 13 23:42:50.916018 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 13 23:42:50.916025 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 13 23:42:50.916116 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 13 23:42:50.916128 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 13 23:42:50.916136 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 13 23:42:50.916143 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 13 23:42:50.916151 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 13 23:42:50.916158 kernel: PCI: CLS 0 bytes, default 64 May 13 23:42:50.916166 kernel: kvm [1]: HYP mode not available May 13 23:42:50.916173 kernel: Initialise system trusted keyrings May 13 23:42:50.916181 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 13 23:42:50.916188 kernel: Key type asymmetric registered May 13 23:42:50.916197 kernel: Asymmetric key parser 'x509' registered May 13 23:42:50.916205 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 13 23:42:50.916212 kernel: io scheduler mq-deadline registered May 13 23:42:50.916220 kernel: io scheduler kyber registered May 13 23:42:50.916227 kernel: io scheduler bfq registered May 13 23:42:50.916235 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 13 23:42:50.916242 kernel: ACPI: button: Power Button [PWRB] May 13 23:42:50.916250 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 13 23:42:50.916333 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 13 23:42:50.916347 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 13 23:42:50.916355 kernel: thunder_xcv, ver 1.0 May 13 23:42:50.916362 kernel: thunder_bgx, ver 1.0 May 13 23:42:50.916370 kernel: nicpf, ver 1.0 May 13 23:42:50.916377 kernel: nicvf, ver 1.0 May 13 23:42:50.916453 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 13 23:42:50.916517 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-13T23:42:50 UTC (1747179770) May 13 23:42:50.916527 kernel: hid: raw HID events driver (C) Jiri Kosina May 13 23:42:50.916537 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 13 23:42:50.916545 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 13 23:42:50.916553 kernel: watchdog: Hard watchdog permanently disabled May 13 23:42:50.916561 kernel: NET: Registered PF_INET6 protocol family May 13 23:42:50.916568 kernel: Segment Routing with IPv6 May 13 23:42:50.916576 kernel: In-situ OAM (IOAM) with IPv6 May 13 23:42:50.916583 kernel: NET: Registered PF_PACKET protocol family May 13 23:42:50.916590 kernel: Key type dns_resolver registered May 13 23:42:50.916598 kernel: registered taskstats version 1 May 13 23:42:50.916606 kernel: Loading compiled-in X.509 certificates May 13 23:42:50.916615 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: 568a15bbab977599d8f910f319ba50c03c8a57bd' May 13 23:42:50.916623 kernel: Key type .fscrypt registered May 13 23:42:50.916630 kernel: Key type fscrypt-provisioning registered May 13 23:42:50.916638 kernel: ima: No TPM chip found, activating TPM-bypass! May 13 23:42:50.916645 kernel: ima: Allocated hash algorithm: sha1 May 13 23:42:50.916653 kernel: ima: No architecture policies found May 13 23:42:50.916660 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 13 23:42:50.916668 kernel: clk: Disabling unused clocks May 13 23:42:50.916677 kernel: Freeing unused kernel memory: 38464K May 13 23:42:50.916684 kernel: Run /init as init process May 13 23:42:50.916692 kernel: with arguments: May 13 23:42:50.916699 kernel: /init May 13 23:42:50.916707 kernel: with environment: May 13 23:42:50.916714 kernel: HOME=/ May 13 23:42:50.916721 kernel: TERM=linux May 13 23:42:50.916728 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 13 23:42:50.916737 systemd[1]: Successfully made /usr/ read-only. May 13 23:42:50.916749 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) May 13 23:42:50.916758 systemd[1]: Detected virtualization kvm. May 13 23:42:50.916766 systemd[1]: Detected architecture arm64. May 13 23:42:50.916773 systemd[1]: Running in initrd. May 13 23:42:50.916781 systemd[1]: No hostname configured, using default hostname. May 13 23:42:50.916790 systemd[1]: Hostname set to . May 13 23:42:50.916798 systemd[1]: Initializing machine ID from VM UUID. May 13 23:42:50.916807 systemd[1]: Queued start job for default target initrd.target. May 13 23:42:50.916816 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:42:50.916824 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:42:50.916841 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 13 23:42:50.916850 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:42:50.916858 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 13 23:42:50.916871 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 13 23:42:50.916883 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 13 23:42:50.916892 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 13 23:42:50.916900 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:42:50.916908 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:42:50.916916 systemd[1]: Reached target paths.target - Path Units. May 13 23:42:50.916924 systemd[1]: Reached target slices.target - Slice Units. May 13 23:42:50.916932 systemd[1]: Reached target swap.target - Swaps. May 13 23:42:50.916941 systemd[1]: Reached target timers.target - Timer Units. May 13 23:42:50.916949 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:42:50.916958 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:42:50.916967 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 13 23:42:50.916975 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 13 23:42:50.916983 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:42:50.916991 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:42:50.917000 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:42:50.917008 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:42:50.917020 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 13 23:42:50.917042 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:42:50.917051 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 13 23:42:50.917060 systemd[1]: Starting systemd-fsck-usr.service... May 13 23:42:50.917068 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:42:50.917201 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:42:50.917211 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:42:50.917219 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:42:50.917228 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 13 23:42:50.917244 systemd[1]: Finished systemd-fsck-usr.service. May 13 23:42:50.917253 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 13 23:42:50.917877 systemd-journald[237]: Collecting audit messages is disabled. May 13 23:42:50.917910 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:42:50.917920 systemd-journald[237]: Journal started May 13 23:42:50.917940 systemd-journald[237]: Runtime Journal (/run/log/journal/519a5ff1633345fa9e765eb2ede7eb92) is 5.9M, max 47.3M, 41.4M free. May 13 23:42:50.920214 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 13 23:42:50.906068 systemd-modules-load[239]: Inserted module 'overlay' May 13 23:42:50.924341 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:42:50.925458 kernel: Bridge firewalling registered May 13 23:42:50.926526 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:42:50.925987 systemd-modules-load[239]: Inserted module 'br_netfilter' May 13 23:42:50.936681 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:42:50.938014 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 13 23:42:50.944066 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:42:50.947253 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:42:50.955101 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:42:50.959250 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:42:50.960972 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:42:50.962971 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:42:50.966553 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 13 23:42:50.967741 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:42:50.971237 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:42:50.990320 dracut-cmdline[277]: dracut-dracut-053 May 13 23:42:50.993209 dracut-cmdline[277]: Using kernel command line parameters: rd.driver.pre=btrfs 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=3174b2682629aa8ad4069807ed6fd62c10f62266ee1e150a1104f2a2fb6489b5 May 13 23:42:51.014302 systemd-resolved[279]: Positive Trust Anchors: May 13 23:42:51.014321 systemd-resolved[279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:42:51.014351 systemd-resolved[279]: 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 May 13 23:42:51.019391 systemd-resolved[279]: Defaulting to hostname 'linux'. May 13 23:42:51.020436 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:42:51.024405 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:42:51.069065 kernel: SCSI subsystem initialized May 13 23:42:51.074052 kernel: Loading iSCSI transport class v2.0-870. May 13 23:42:51.082077 kernel: iscsi: registered transport (tcp) May 13 23:42:51.095048 kernel: iscsi: registered transport (qla4xxx) May 13 23:42:51.095090 kernel: QLogic iSCSI HBA Driver May 13 23:42:51.141496 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 13 23:42:51.144000 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 13 23:42:51.178718 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 13 23:42:51.178792 kernel: device-mapper: uevent: version 1.0.3 May 13 23:42:51.178805 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 13 23:42:51.225074 kernel: raid6: neonx8 gen() 15766 MB/s May 13 23:42:51.242066 kernel: raid6: neonx4 gen() 15799 MB/s May 13 23:42:51.259073 kernel: raid6: neonx2 gen() 13245 MB/s May 13 23:42:51.276075 kernel: raid6: neonx1 gen() 10457 MB/s May 13 23:42:51.293061 kernel: raid6: int64x8 gen() 6785 MB/s May 13 23:42:51.310056 kernel: raid6: int64x4 gen() 7343 MB/s May 13 23:42:51.327075 kernel: raid6: int64x2 gen() 6099 MB/s May 13 23:42:51.344271 kernel: raid6: int64x1 gen() 5044 MB/s May 13 23:42:51.344318 kernel: raid6: using algorithm neonx4 gen() 15799 MB/s May 13 23:42:51.362261 kernel: raid6: .... xor() 12353 MB/s, rmw enabled May 13 23:42:51.362319 kernel: raid6: using neon recovery algorithm May 13 23:42:51.367075 kernel: xor: measuring software checksum speed May 13 23:42:51.368404 kernel: 8regs : 18215 MB/sec May 13 23:42:51.368421 kernel: 32regs : 21601 MB/sec May 13 23:42:51.369679 kernel: arm64_neon : 27542 MB/sec May 13 23:42:51.369692 kernel: xor: using function: arm64_neon (27542 MB/sec) May 13 23:42:51.424087 kernel: Btrfs loaded, zoned=no, fsverity=no May 13 23:42:51.435844 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 13 23:42:51.438672 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:42:51.462696 systemd-udevd[464]: Using default interface naming scheme 'v255'. May 13 23:42:51.466395 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:42:51.471214 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 13 23:42:51.495822 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation May 13 23:42:51.525286 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:42:51.527770 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:42:51.583517 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:42:51.586691 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 13 23:42:51.609007 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 13 23:42:51.611996 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:42:51.614747 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:42:51.617167 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:42:51.620113 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 13 23:42:51.642494 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 13 23:42:51.649477 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 13 23:42:51.654861 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 13 23:42:51.662011 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 13 23:42:51.662086 kernel: GPT:9289727 != 19775487 May 13 23:42:51.662107 kernel: GPT:Alternate GPT header not at the end of the disk. May 13 23:42:51.662117 kernel: GPT:9289727 != 19775487 May 13 23:42:51.662127 kernel: GPT: Use GNU Parted to correct GPT errors. May 13 23:42:51.662138 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:42:51.663849 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:42:51.663987 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:42:51.669141 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:42:51.670533 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:42:51.670704 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:42:51.673343 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:42:51.678539 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:42:51.691070 kernel: BTRFS: device fsid ee830c17-a93d-4109-bd12-3fec8ef6763d devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (512) May 13 23:42:51.691122 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (523) May 13 23:42:51.701092 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:42:51.714083 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 13 23:42:51.733676 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 13 23:42:51.740239 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 13 23:42:51.741540 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 13 23:42:51.750960 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 13 23:42:51.753276 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 13 23:42:51.755150 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 13 23:42:51.773368 disk-uuid[557]: Primary Header is updated. May 13 23:42:51.773368 disk-uuid[557]: Secondary Entries is updated. May 13 23:42:51.773368 disk-uuid[557]: Secondary Header is updated. May 13 23:42:51.780139 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:42:51.785705 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:42:52.786067 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 13 23:42:52.786565 disk-uuid[562]: The operation has completed successfully. May 13 23:42:52.816605 systemd[1]: disk-uuid.service: Deactivated successfully. May 13 23:42:52.816713 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 13 23:42:52.839605 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 13 23:42:52.855899 sh[577]: Success May 13 23:42:52.872077 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 13 23:42:52.910102 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 13 23:42:52.913059 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 13 23:42:52.928937 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 13 23:42:52.935129 kernel: BTRFS info (device dm-0): first mount of filesystem ee830c17-a93d-4109-bd12-3fec8ef6763d May 13 23:42:52.935165 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 13 23:42:52.935177 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 13 23:42:52.937615 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 13 23:42:52.937636 kernel: BTRFS info (device dm-0): using free space tree May 13 23:42:52.941283 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 13 23:42:52.942685 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 13 23:42:52.943506 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 13 23:42:52.946321 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 13 23:42:52.965187 kernel: BTRFS info (device vda6): first mount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:42:52.965243 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 23:42:52.965255 kernel: BTRFS info (device vda6): using free space tree May 13 23:42:52.968126 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:42:52.973119 kernel: BTRFS info (device vda6): last unmount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:42:53.005893 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 13 23:42:53.008209 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 13 23:42:53.046121 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:42:53.049459 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:42:53.097903 systemd-networkd[756]: lo: Link UP May 13 23:42:53.097918 systemd-networkd[756]: lo: Gained carrier May 13 23:42:53.098773 systemd-networkd[756]: Enumeration completed May 13 23:42:53.099283 systemd-networkd[756]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:42:53.099286 systemd-networkd[756]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:42:53.100259 systemd-networkd[756]: eth0: Link UP May 13 23:42:53.100262 systemd-networkd[756]: eth0: Gained carrier May 13 23:42:53.100269 systemd-networkd[756]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:42:53.103161 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:42:53.105274 systemd[1]: Reached target network.target - Network. May 13 23:42:53.130138 systemd-networkd[756]: eth0: DHCPv4 address 10.0.0.65/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 13 23:42:53.134286 ignition[719]: Ignition 2.20.0 May 13 23:42:53.134300 ignition[719]: Stage: fetch-offline May 13 23:42:53.134332 ignition[719]: no configs at "/usr/lib/ignition/base.d" May 13 23:42:53.134340 ignition[719]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:42:53.134497 ignition[719]: parsed url from cmdline: "" May 13 23:42:53.134503 ignition[719]: no config URL provided May 13 23:42:53.134508 ignition[719]: reading system config file "/usr/lib/ignition/user.ign" May 13 23:42:53.134516 ignition[719]: no config at "/usr/lib/ignition/user.ign" May 13 23:42:53.134540 ignition[719]: op(1): [started] loading QEMU firmware config module May 13 23:42:53.134544 ignition[719]: op(1): executing: "modprobe" "qemu_fw_cfg" May 13 23:42:53.144112 ignition[719]: op(1): [finished] loading QEMU firmware config module May 13 23:42:53.150676 ignition[719]: parsing config with SHA512: 4eb0f554c4172a7b8accd1307f51a53f09621c38f176f915ecfdc652721967dd56d20cd3a3d464348734765554cf6d63b9df0191ca22a3a6fd59a17bb4537367 May 13 23:42:53.156099 unknown[719]: fetched base config from "system" May 13 23:42:53.156110 unknown[719]: fetched user config from "qemu" May 13 23:42:53.156412 ignition[719]: fetch-offline: fetch-offline passed May 13 23:42:53.156482 ignition[719]: Ignition finished successfully May 13 23:42:53.160069 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:42:53.161671 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 13 23:42:53.162529 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 13 23:42:53.185240 ignition[771]: Ignition 2.20.0 May 13 23:42:53.185250 ignition[771]: Stage: kargs May 13 23:42:53.185416 ignition[771]: no configs at "/usr/lib/ignition/base.d" May 13 23:42:53.185426 ignition[771]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:42:53.189185 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 13 23:42:53.186127 ignition[771]: kargs: kargs passed May 13 23:42:53.186174 ignition[771]: Ignition finished successfully May 13 23:42:53.191684 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 13 23:42:53.214962 ignition[779]: Ignition 2.20.0 May 13 23:42:53.214973 ignition[779]: Stage: disks May 13 23:42:53.215329 ignition[779]: no configs at "/usr/lib/ignition/base.d" May 13 23:42:53.217852 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 13 23:42:53.215340 ignition[779]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:42:53.219451 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 13 23:42:53.216240 ignition[779]: disks: disks passed May 13 23:42:53.221158 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 13 23:42:53.216297 ignition[779]: Ignition finished successfully May 13 23:42:53.223225 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:42:53.225127 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:42:53.226726 systemd[1]: Reached target basic.target - Basic System. May 13 23:42:53.229825 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 13 23:42:53.259721 systemd-fsck[790]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 13 23:42:53.265990 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 13 23:42:53.268413 systemd[1]: Mounting sysroot.mount - /sysroot... May 13 23:42:53.339056 kernel: EXT4-fs (vda9): mounted filesystem 9f8d74e6-c079-469f-823a-18a62077a2c7 r/w with ordered data mode. Quota mode: none. May 13 23:42:53.339813 systemd[1]: Mounted sysroot.mount - /sysroot. May 13 23:42:53.341306 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 13 23:42:53.343959 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:42:53.345940 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 13 23:42:53.347275 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 13 23:42:53.347415 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 13 23:42:53.347456 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:42:53.355972 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 13 23:42:53.358750 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 13 23:42:53.367077 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (798) May 13 23:42:53.372795 kernel: BTRFS info (device vda6): first mount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:42:53.372850 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 23:42:53.372862 kernel: BTRFS info (device vda6): using free space tree May 13 23:42:53.383060 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:42:53.384713 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:42:53.434320 initrd-setup-root[823]: cut: /sysroot/etc/passwd: No such file or directory May 13 23:42:53.443446 initrd-setup-root[830]: cut: /sysroot/etc/group: No such file or directory May 13 23:42:53.447704 initrd-setup-root[837]: cut: /sysroot/etc/shadow: No such file or directory May 13 23:42:53.451640 initrd-setup-root[844]: cut: /sysroot/etc/gshadow: No such file or directory May 13 23:42:53.549479 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 13 23:42:53.553288 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 13 23:42:53.556068 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 13 23:42:53.570245 kernel: BTRFS info (device vda6): last unmount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:42:53.588267 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 13 23:42:53.608079 ignition[913]: INFO : Ignition 2.20.0 May 13 23:42:53.608079 ignition[913]: INFO : Stage: mount May 13 23:42:53.609796 ignition[913]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:42:53.609796 ignition[913]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:42:53.609796 ignition[913]: INFO : mount: mount passed May 13 23:42:53.609796 ignition[913]: INFO : Ignition finished successfully May 13 23:42:53.611490 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 13 23:42:53.614084 systemd[1]: Starting ignition-files.service - Ignition (files)... May 13 23:42:53.934057 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 13 23:42:53.935634 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 13 23:42:53.963915 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (927) May 13 23:42:53.963965 kernel: BTRFS info (device vda6): first mount of filesystem e7b30525-8b14-4004-ad68-68a99b3959db May 13 23:42:53.963976 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 13 23:42:53.965508 kernel: BTRFS info (device vda6): using free space tree May 13 23:42:53.968060 kernel: BTRFS info (device vda6): auto enabling async discard May 13 23:42:53.969025 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 13 23:42:53.995733 ignition[944]: INFO : Ignition 2.20.0 May 13 23:42:53.995733 ignition[944]: INFO : Stage: files May 13 23:42:53.997685 ignition[944]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:42:53.997685 ignition[944]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:42:53.997685 ignition[944]: DEBUG : files: compiled without relabeling support, skipping May 13 23:42:53.997685 ignition[944]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 13 23:42:53.997685 ignition[944]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 13 23:42:54.004052 ignition[944]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 13 23:42:54.004052 ignition[944]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 13 23:42:54.004052 ignition[944]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 13 23:42:54.004052 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 13 23:42:54.004052 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 13 23:42:54.004052 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:42:54.004052 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 13 23:42:54.004052 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 13 23:42:54.004052 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 13 23:42:54.004052 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 13 23:42:54.001719 unknown[944]: wrote ssh authorized keys file for user: core May 13 23:42:54.022582 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-arm64.raw: attempt #1 May 13 23:42:54.336261 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 13 23:42:54.696946 ignition[944]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 13 23:42:54.696946 ignition[944]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 13 23:42:54.700617 ignition[944]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 13 23:42:54.700617 ignition[944]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 13 23:42:54.700617 ignition[944]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 13 23:42:54.700617 ignition[944]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 13 23:42:54.737490 ignition[944]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 13 23:42:54.740923 ignition[944]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 13 23:42:54.743274 ignition[944]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 13 23:42:54.743274 ignition[944]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 13 23:42:54.743274 ignition[944]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 13 23:42:54.743274 ignition[944]: INFO : files: files passed May 13 23:42:54.743274 ignition[944]: INFO : Ignition finished successfully May 13 23:42:54.746071 systemd[1]: Finished ignition-files.service - Ignition (files). May 13 23:42:54.749207 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 13 23:42:54.752337 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 13 23:42:54.772157 systemd[1]: ignition-quench.service: Deactivated successfully. May 13 23:42:54.772259 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 13 23:42:54.775215 initrd-setup-root-after-ignition[975]: grep: /sysroot/oem/oem-release: No such file or directory May 13 23:42:54.776725 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:42:54.776725 initrd-setup-root-after-ignition[977]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 13 23:42:54.780579 initrd-setup-root-after-ignition[981]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 13 23:42:54.780866 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:42:54.783631 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 13 23:42:54.786440 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 13 23:42:54.852592 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 13 23:42:54.853774 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 13 23:42:54.866394 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 13 23:42:54.867412 systemd[1]: Reached target initrd.target - Initrd Default Target. May 13 23:42:54.868438 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 13 23:42:54.869282 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 13 23:42:54.901161 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:42:54.903716 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 13 23:42:54.925198 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 13 23:42:54.926471 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:42:54.929190 systemd[1]: Stopped target timers.target - Timer Units. May 13 23:42:54.931240 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 13 23:42:54.931372 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 13 23:42:54.933891 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 13 23:42:54.934980 systemd[1]: Stopped target basic.target - Basic System. May 13 23:42:54.936891 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 13 23:42:54.938793 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 13 23:42:54.940551 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 13 23:42:54.942433 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 13 23:42:54.945477 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 13 23:42:54.947490 systemd[1]: Stopped target sysinit.target - System Initialization. May 13 23:42:54.949273 systemd[1]: Stopped target local-fs.target - Local File Systems. May 13 23:42:54.951147 systemd[1]: Stopped target swap.target - Swaps. May 13 23:42:54.952706 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 13 23:42:54.952842 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 13 23:42:54.955239 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 13 23:42:54.960199 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:42:54.962141 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 13 23:42:54.966101 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:42:54.967361 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 13 23:42:54.967493 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 13 23:42:54.973167 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 13 23:42:54.973297 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 13 23:42:54.975599 systemd[1]: Stopped target paths.target - Path Units. May 13 23:42:54.977374 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 13 23:42:54.981558 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:42:54.982869 systemd[1]: Stopped target slices.target - Slice Units. May 13 23:42:54.984448 systemd[1]: Stopped target sockets.target - Socket Units. May 13 23:42:54.988961 systemd[1]: iscsid.socket: Deactivated successfully. May 13 23:42:54.989081 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 13 23:42:54.991237 systemd[1]: iscsiuio.socket: Deactivated successfully. May 13 23:42:54.991312 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 13 23:42:54.993885 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 13 23:42:54.994099 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 13 23:42:54.995982 systemd[1]: ignition-files.service: Deactivated successfully. May 13 23:42:54.996112 systemd[1]: Stopped ignition-files.service - Ignition (files). May 13 23:42:54.998520 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 13 23:42:55.001085 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 13 23:42:55.001221 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:42:55.026776 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 13 23:42:55.027710 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 13 23:42:55.027847 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:42:55.030154 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 13 23:42:55.030297 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 13 23:42:55.033111 systemd-networkd[756]: eth0: Gained IPv6LL May 13 23:42:55.036844 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 13 23:42:55.038625 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 13 23:42:55.043176 ignition[1001]: INFO : Ignition 2.20.0 May 13 23:42:55.043176 ignition[1001]: INFO : Stage: umount May 13 23:42:55.052246 ignition[1001]: INFO : no configs at "/usr/lib/ignition/base.d" May 13 23:42:55.052246 ignition[1001]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 13 23:42:55.052246 ignition[1001]: INFO : umount: umount passed May 13 23:42:55.052246 ignition[1001]: INFO : Ignition finished successfully May 13 23:42:55.045207 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 13 23:42:55.050625 systemd[1]: ignition-mount.service: Deactivated successfully. May 13 23:42:55.050730 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 13 23:42:55.054087 systemd[1]: Stopped target network.target - Network. May 13 23:42:55.055018 systemd[1]: ignition-disks.service: Deactivated successfully. May 13 23:42:55.055152 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 13 23:42:55.057600 systemd[1]: ignition-kargs.service: Deactivated successfully. May 13 23:42:55.057657 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 13 23:42:55.061113 systemd[1]: ignition-setup.service: Deactivated successfully. May 13 23:42:55.061167 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 13 23:42:55.062924 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 13 23:42:55.062974 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 13 23:42:55.070384 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 13 23:42:55.072153 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 13 23:42:55.079942 systemd[1]: systemd-resolved.service: Deactivated successfully. May 13 23:42:55.081218 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 13 23:42:55.086392 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 13 23:42:55.086645 systemd[1]: systemd-networkd.service: Deactivated successfully. May 13 23:42:55.086743 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 13 23:42:55.094976 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 13 23:42:55.095608 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 13 23:42:55.095662 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 13 23:42:55.098458 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 13 23:42:55.099507 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 13 23:42:55.099570 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 13 23:42:55.101928 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 13 23:42:55.101974 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 13 23:42:55.104650 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 13 23:42:55.104697 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 13 23:42:55.107048 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 13 23:42:55.107105 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:42:55.110376 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:42:55.117669 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 13 23:42:55.117734 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 13 23:42:55.132298 systemd[1]: systemd-udevd.service: Deactivated successfully. May 13 23:42:55.132469 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:42:55.135420 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 13 23:42:55.135474 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 13 23:42:55.136937 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 13 23:42:55.136968 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:42:55.138875 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 13 23:42:55.138928 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 13 23:42:55.141916 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 13 23:42:55.141977 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 13 23:42:55.144812 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 13 23:42:55.144866 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 13 23:42:55.147821 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 13 23:42:55.148904 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 13 23:42:55.148960 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:42:55.151781 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 13 23:42:55.151824 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:42:55.155842 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 13 23:42:55.155896 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 13 23:42:55.157249 systemd[1]: sysroot-boot.service: Deactivated successfully. May 13 23:42:55.157357 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 13 23:42:55.159432 systemd[1]: network-cleanup.service: Deactivated successfully. May 13 23:42:55.159540 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 13 23:42:55.162893 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 13 23:42:55.162982 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 13 23:42:55.165487 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 13 23:42:55.166690 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 13 23:42:55.166752 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 13 23:42:55.169592 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 13 23:42:55.183319 systemd[1]: Switching root. May 13 23:42:55.215135 systemd-journald[237]: Journal stopped May 13 23:42:56.080651 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). May 13 23:42:56.080709 kernel: SELinux: policy capability network_peer_controls=1 May 13 23:42:56.080722 kernel: SELinux: policy capability open_perms=1 May 13 23:42:56.080731 kernel: SELinux: policy capability extended_socket_class=1 May 13 23:42:56.080741 kernel: SELinux: policy capability always_check_network=0 May 13 23:42:56.080756 kernel: SELinux: policy capability cgroup_seclabel=1 May 13 23:42:56.080766 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 13 23:42:56.080776 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 13 23:42:56.080786 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 13 23:42:56.080799 kernel: audit: type=1403 audit(1747179775.402:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 13 23:42:56.080809 systemd[1]: Successfully loaded SELinux policy in 32.411ms. May 13 23:42:56.080829 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.829ms. May 13 23:42:56.080841 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) May 13 23:42:56.080852 systemd[1]: Detected virtualization kvm. May 13 23:42:56.080865 systemd[1]: Detected architecture arm64. May 13 23:42:56.080875 systemd[1]: Detected first boot. May 13 23:42:56.080886 systemd[1]: Initializing machine ID from VM UUID. May 13 23:42:56.080896 zram_generator::config[1049]: No configuration found. May 13 23:42:56.080907 kernel: NET: Registered PF_VSOCK protocol family May 13 23:42:56.080917 systemd[1]: Populated /etc with preset unit settings. May 13 23:42:56.080928 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 13 23:42:56.080938 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 13 23:42:56.080948 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 13 23:42:56.080960 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 13 23:42:56.080980 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 13 23:42:56.080993 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 13 23:42:56.081003 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 13 23:42:56.081018 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 13 23:42:56.081038 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 13 23:42:56.081051 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 13 23:42:56.081063 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 13 23:42:56.081075 systemd[1]: Created slice user.slice - User and Session Slice. May 13 23:42:56.081086 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 13 23:42:56.081097 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 13 23:42:56.081107 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 13 23:42:56.081118 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 13 23:42:56.081128 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 13 23:42:56.081139 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 13 23:42:56.081150 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 13 23:42:56.081160 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 13 23:42:56.081175 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 13 23:42:56.081186 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 13 23:42:56.081197 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 13 23:42:56.081208 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 13 23:42:56.081219 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 13 23:42:56.081229 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 13 23:42:56.081239 systemd[1]: Reached target slices.target - Slice Units. May 13 23:42:56.081250 systemd[1]: Reached target swap.target - Swaps. May 13 23:42:56.081262 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 13 23:42:56.081273 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 13 23:42:56.081283 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 13 23:42:56.081293 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 13 23:42:56.081303 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 13 23:42:56.081314 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 13 23:42:56.081324 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 13 23:42:56.081334 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 13 23:42:56.081345 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 13 23:42:56.081356 systemd[1]: Mounting media.mount - External Media Directory... May 13 23:42:56.081367 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 13 23:42:56.081377 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 13 23:42:56.081387 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 13 23:42:56.081398 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). May 13 23:42:56.081408 systemd[1]: Reached target machines.target - Containers. May 13 23:42:56.081419 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 13 23:42:56.081429 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:42:56.081441 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 13 23:42:56.081452 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 13 23:42:56.081462 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:42:56.081473 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:42:56.081483 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:42:56.081493 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 13 23:42:56.081504 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:42:56.081514 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 13 23:42:56.081525 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 13 23:42:56.081537 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 13 23:42:56.081547 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 13 23:42:56.081558 systemd[1]: Stopped systemd-fsck-usr.service. May 13 23:42:56.081569 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). May 13 23:42:56.081580 kernel: fuse: init (API version 7.39) May 13 23:42:56.081589 kernel: loop: module loaded May 13 23:42:56.081599 systemd[1]: Starting systemd-journald.service - Journal Service... May 13 23:42:56.081608 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 13 23:42:56.081620 kernel: ACPI: bus type drm_connector registered May 13 23:42:56.081630 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 13 23:42:56.081640 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 13 23:42:56.081650 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 13 23:42:56.081661 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 13 23:42:56.081671 systemd[1]: verity-setup.service: Deactivated successfully. May 13 23:42:56.081681 systemd[1]: Stopped verity-setup.service. May 13 23:42:56.081711 systemd-journald[1124]: Collecting audit messages is disabled. May 13 23:42:56.081732 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 13 23:42:56.081743 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 13 23:42:56.081753 systemd-journald[1124]: Journal started May 13 23:42:56.081775 systemd-journald[1124]: Runtime Journal (/run/log/journal/519a5ff1633345fa9e765eb2ede7eb92) is 5.9M, max 47.3M, 41.4M free. May 13 23:42:55.845287 systemd[1]: Queued start job for default target multi-user.target. May 13 23:42:55.858189 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 13 23:42:55.858596 systemd[1]: systemd-journald.service: Deactivated successfully. May 13 23:42:56.083536 systemd[1]: Mounted media.mount - External Media Directory. May 13 23:42:56.085527 systemd[1]: Started systemd-journald.service - Journal Service. May 13 23:42:56.086270 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 13 23:42:56.087512 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 13 23:42:56.088737 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 13 23:42:56.090123 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 13 23:42:56.091659 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 13 23:42:56.093269 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 13 23:42:56.093449 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 13 23:42:56.094872 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:42:56.096113 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:42:56.097489 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:42:56.097673 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:42:56.099004 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:42:56.099194 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:42:56.100752 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 13 23:42:56.100926 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 13 23:42:56.102354 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:42:56.104076 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:42:56.105610 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 13 23:42:56.108085 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 13 23:42:56.109656 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 13 23:42:56.111259 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 13 23:42:56.123837 systemd[1]: Reached target network-pre.target - Preparation for Network. May 13 23:42:56.126685 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 13 23:42:56.128926 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 13 23:42:56.130061 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 13 23:42:56.130108 systemd[1]: Reached target local-fs.target - Local File Systems. May 13 23:42:56.132132 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 13 23:42:56.141907 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 13 23:42:56.144045 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 13 23:42:56.145176 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:42:56.146440 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 13 23:42:56.148380 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 13 23:42:56.149653 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:42:56.153176 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 13 23:42:56.154444 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:42:56.157512 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 13 23:42:56.157662 systemd-journald[1124]: Time spent on flushing to /var/log/journal/519a5ff1633345fa9e765eb2ede7eb92 is 12.686ms for 851 entries. May 13 23:42:56.157662 systemd-journald[1124]: System Journal (/var/log/journal/519a5ff1633345fa9e765eb2ede7eb92) is 8M, max 195.6M, 187.6M free. May 13 23:42:56.176335 systemd-journald[1124]: Received client request to flush runtime journal. May 13 23:42:56.160816 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 13 23:42:56.165655 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 13 23:42:56.173443 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 13 23:42:56.177390 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 13 23:42:56.178747 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 13 23:42:56.180235 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 13 23:42:56.184509 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 13 23:42:56.186542 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 13 23:42:56.193325 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 13 23:42:56.196992 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 13 23:42:56.200576 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 13 23:42:56.203067 kernel: loop0: detected capacity change from 0 to 103832 May 13 23:42:56.217734 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 13 23:42:56.222108 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 13 23:42:56.222275 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 13 23:42:56.226717 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 13 23:42:56.232393 udevadm[1179]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 13 23:42:56.242583 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 13 23:42:56.245090 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 13 23:42:56.255277 kernel: loop1: detected capacity change from 0 to 126448 May 13 23:42:56.259648 systemd-tmpfiles[1185]: ACLs are not supported, ignoring. May 13 23:42:56.259666 systemd-tmpfiles[1185]: ACLs are not supported, ignoring. May 13 23:42:56.268802 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 13 23:42:56.287085 kernel: loop2: detected capacity change from 0 to 201592 May 13 23:42:56.324078 kernel: loop3: detected capacity change from 0 to 103832 May 13 23:42:56.330054 kernel: loop4: detected capacity change from 0 to 126448 May 13 23:42:56.335059 kernel: loop5: detected capacity change from 0 to 201592 May 13 23:42:56.339809 (sd-merge)[1191]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 13 23:42:56.340251 (sd-merge)[1191]: Merged extensions into '/usr'. May 13 23:42:56.343811 systemd[1]: Reload requested from client PID 1166 ('systemd-sysext') (unit systemd-sysext.service)... May 13 23:42:56.343833 systemd[1]: Reloading... May 13 23:42:56.403209 zram_generator::config[1218]: No configuration found. May 13 23:42:56.456548 ldconfig[1161]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 13 23:42:56.517233 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. May 13 23:42:56.567385 systemd[1]: Reloading finished in 221 ms. May 13 23:42:56.586070 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 13 23:42:56.587611 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 13 23:42:56.606489 systemd[1]: Starting ensure-sysext.service... May 13 23:42:56.608647 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 13 23:42:56.620611 systemd[1]: Reload requested from client PID 1255 ('systemctl') (unit ensure-sysext.service)... May 13 23:42:56.620632 systemd[1]: Reloading... May 13 23:42:56.636045 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 13 23:42:56.636297 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 13 23:42:56.636908 systemd-tmpfiles[1256]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 13 23:42:56.637577 systemd-tmpfiles[1256]: ACLs are not supported, ignoring. May 13 23:42:56.637697 systemd-tmpfiles[1256]: ACLs are not supported, ignoring. May 13 23:42:56.645782 systemd-tmpfiles[1256]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:42:56.645973 systemd-tmpfiles[1256]: Skipping /boot May 13 23:42:56.656896 systemd-tmpfiles[1256]: Detected autofs mount point /boot during canonicalization of boot. May 13 23:42:56.657087 systemd-tmpfiles[1256]: Skipping /boot May 13 23:42:56.684067 zram_generator::config[1285]: No configuration found. May 13 23:42:56.781186 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. May 13 23:42:56.831272 systemd[1]: Reloading finished in 210 ms. May 13 23:42:56.845071 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 13 23:42:56.852109 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 13 23:42:56.872292 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 13 23:42:56.874928 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 13 23:42:56.886769 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 13 23:42:56.894398 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 13 23:42:56.901707 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 13 23:42:56.904481 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 13 23:42:56.912133 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 13 23:42:56.916973 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:42:56.932800 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:42:56.935294 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:42:56.943164 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:42:56.944366 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:42:56.944560 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). May 13 23:42:56.946090 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 13 23:42:56.948502 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 13 23:42:56.950456 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:42:56.950630 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:42:56.959483 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:42:56.959653 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:42:56.961534 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:42:56.961821 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:42:56.964681 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 13 23:42:56.983016 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 13 23:42:56.993368 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 13 23:42:56.997456 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 13 23:42:57.003204 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 13 23:42:57.005534 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 13 23:42:57.008809 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 13 23:42:57.010250 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 13 23:42:57.010335 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). May 13 23:42:57.025758 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 13 23:42:57.026933 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). May 13 23:42:57.027880 systemd[1]: Finished ensure-sysext.service. May 13 23:42:57.029359 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 13 23:42:57.029520 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 13 23:42:57.031005 systemd[1]: modprobe@drm.service: Deactivated successfully. May 13 23:42:57.031193 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 13 23:42:57.032612 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 13 23:42:57.032762 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 13 23:42:57.034319 systemd[1]: modprobe@loop.service: Deactivated successfully. May 13 23:42:57.034470 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 13 23:42:57.046225 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 13 23:42:57.046370 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 13 23:42:57.046437 systemd-udevd[1326]: Using default interface naming scheme 'v255'. May 13 23:42:57.051050 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 13 23:42:57.065772 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 13 23:42:57.086028 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 13 23:42:57.128280 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 13 23:42:57.156884 augenrules[1390]: No rules May 13 23:42:57.161286 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 13 23:42:57.166918 systemd[1]: audit-rules.service: Deactivated successfully. May 13 23:42:57.167462 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 13 23:42:57.181880 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1369) May 13 23:42:57.252389 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 13 23:42:57.265288 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 13 23:42:57.282318 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 13 23:42:57.283892 systemd[1]: Reached target time-set.target - System Time Set. May 13 23:42:57.316974 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 13 23:42:57.320236 systemd-resolved[1325]: Positive Trust Anchors: May 13 23:42:57.320254 systemd-resolved[1325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 13 23:42:57.320286 systemd-resolved[1325]: 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 May 13 23:42:57.329635 systemd-resolved[1325]: Defaulting to hostname 'linux'. May 13 23:42:57.334136 systemd-networkd[1385]: lo: Link UP May 13 23:42:57.334145 systemd-networkd[1385]: lo: Gained carrier May 13 23:42:57.335646 systemd-networkd[1385]: Enumeration completed May 13 23:42:57.335790 systemd[1]: Started systemd-networkd.service - Network Configuration. May 13 23:42:57.337302 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 13 23:42:57.341475 systemd[1]: Reached target network.target - Network. May 13 23:42:57.342491 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 13 23:42:57.346581 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 13 23:42:57.346587 systemd-networkd[1385]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:42:57.346593 systemd-networkd[1385]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 13 23:42:57.347861 systemd-networkd[1385]: eth0: Link UP May 13 23:42:57.347869 systemd-networkd[1385]: eth0: Gained carrier May 13 23:42:57.347883 systemd-networkd[1385]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 13 23:42:57.350628 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 13 23:42:57.363177 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 13 23:42:57.367138 systemd-networkd[1385]: eth0: DHCPv4 address 10.0.0.65/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 13 23:42:57.370203 systemd-timesyncd[1364]: Network configuration changed, trying to establish connection. May 13 23:42:56.888548 systemd-timesyncd[1364]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 13 23:42:56.908652 systemd-journald[1124]: Time jumped backwards, rotating. May 13 23:42:56.888582 systemd-resolved[1325]: Clock change detected. Flushing caches. May 13 23:42:56.888617 systemd-timesyncd[1364]: Initial clock synchronization to Tue 2025-05-13 23:42:56.888441 UTC. May 13 23:42:56.892089 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 13 23:42:56.894614 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 13 23:42:56.899986 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 13 23:42:56.931848 lvm[1422]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:42:56.946470 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 13 23:42:56.966235 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 13 23:42:56.967782 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 13 23:42:56.968968 systemd[1]: Reached target sysinit.target - System Initialization. May 13 23:42:56.970207 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 13 23:42:56.971498 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 13 23:42:56.973086 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 13 23:42:56.974334 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 13 23:42:56.976083 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 13 23:42:56.977571 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 13 23:42:56.977614 systemd[1]: Reached target paths.target - Path Units. May 13 23:42:56.978628 systemd[1]: Reached target timers.target - Timer Units. May 13 23:42:56.980753 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 13 23:42:56.983348 systemd[1]: Starting docker.socket - Docker Socket for the API... May 13 23:42:56.986787 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 13 23:42:56.988384 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 13 23:42:56.989773 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 13 23:42:56.993176 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 13 23:42:56.995121 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 13 23:42:56.998380 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 13 23:42:57.000290 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 13 23:42:57.001657 systemd[1]: Reached target sockets.target - Socket Units. May 13 23:42:57.002704 systemd[1]: Reached target basic.target - Basic System. May 13 23:42:57.003767 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 13 23:42:57.003803 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 13 23:42:57.014537 systemd[1]: Starting containerd.service - containerd container runtime... May 13 23:42:57.016780 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 13 23:42:57.017850 lvm[1431]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 13 23:42:57.019206 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 13 23:42:57.023761 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 13 23:42:57.025094 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). May 13 23:42:57.026173 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 13 23:42:57.031079 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 13 23:42:57.033285 jq[1434]: false May 13 23:42:57.037332 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 13 23:42:57.041195 systemd[1]: Starting systemd-logind.service - User Login Management... May 13 23:42:57.043367 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 13 23:42:57.043922 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 13 23:42:57.051583 systemd[1]: Starting update-engine.service - Update Engine... May 13 23:42:57.054437 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 13 23:42:57.059556 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 13 23:42:57.062109 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 13 23:42:57.062299 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 13 23:42:57.062584 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 13 23:42:57.062769 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 13 23:42:57.067315 extend-filesystems[1435]: Found loop3 May 13 23:42:57.076826 extend-filesystems[1435]: Found loop4 May 13 23:42:57.076826 extend-filesystems[1435]: Found loop5 May 13 23:42:57.076826 extend-filesystems[1435]: Found vda May 13 23:42:57.076826 extend-filesystems[1435]: Found vda1 May 13 23:42:57.076826 extend-filesystems[1435]: Found vda2 May 13 23:42:57.076826 extend-filesystems[1435]: Found vda3 May 13 23:42:57.076826 extend-filesystems[1435]: Found usr May 13 23:42:57.076826 extend-filesystems[1435]: Found vda4 May 13 23:42:57.076826 extend-filesystems[1435]: Found vda6 May 13 23:42:57.076826 extend-filesystems[1435]: Found vda7 May 13 23:42:57.076826 extend-filesystems[1435]: Found vda9 May 13 23:42:57.076826 extend-filesystems[1435]: Checking size of /dev/vda9 May 13 23:42:57.076219 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 13 23:42:57.075639 dbus-daemon[1433]: [system] SELinux support is enabled May 13 23:42:57.080596 systemd[1]: motdgen.service: Deactivated successfully. May 13 23:42:57.095140 jq[1445]: true May 13 23:42:57.081656 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 13 23:42:57.099025 extend-filesystems[1435]: Resized partition /dev/vda9 May 13 23:42:57.101815 update_engine[1443]: I20250513 23:42:57.101572 1443 main.cc:92] Flatcar Update Engine starting May 13 23:42:57.106946 extend-filesystems[1462]: resize2fs 1.47.2 (1-Jan-2025) May 13 23:42:57.108267 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). May 13 23:42:57.108323 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 13 23:42:57.109140 jq[1458]: true May 13 23:42:57.111251 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). May 13 23:42:57.111281 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 13 23:42:57.115713 (ntainerd)[1459]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 13 23:42:57.117676 update_engine[1443]: I20250513 23:42:57.117517 1443 update_check_scheduler.cc:74] Next update check in 9m58s May 13 23:42:57.120758 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1374) May 13 23:42:57.121838 systemd[1]: Started update-engine.service - Update Engine. May 13 23:42:57.126331 systemd-logind[1442]: Watching system buttons on /dev/input/event0 (Power Button) May 13 23:42:57.127074 systemd-logind[1442]: New seat seat0. May 13 23:42:57.128125 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 13 23:42:57.130824 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 13 23:42:57.132174 systemd[1]: Started systemd-logind.service - User Login Management. May 13 23:42:57.162633 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 13 23:42:57.202495 extend-filesystems[1462]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 13 23:42:57.202495 extend-filesystems[1462]: old_desc_blocks = 1, new_desc_blocks = 1 May 13 23:42:57.202495 extend-filesystems[1462]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 13 23:42:57.211476 extend-filesystems[1435]: Resized filesystem in /dev/vda9 May 13 23:42:57.205453 systemd[1]: extend-filesystems.service: Deactivated successfully. May 13 23:42:57.206829 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 13 23:42:57.225435 bash[1483]: Updated "/home/core/.ssh/authorized_keys" May 13 23:42:57.228123 locksmithd[1468]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 13 23:42:57.228537 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 13 23:42:57.230905 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 13 23:42:57.354891 containerd[1459]: time="2025-05-13T23:42:57Z" 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 May 13 23:42:57.355660 containerd[1459]: time="2025-05-13T23:42:57.355615749Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 May 13 23:42:57.366854 containerd[1459]: time="2025-05-13T23:42:57.366802709Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.28µs" May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.366945309Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.366973429Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367168189Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367185189Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367212629Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367269549Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367283589Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367652389Z" 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 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367676349Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367688149Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367697549Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 13 23:42:57.368152 containerd[1459]: time="2025-05-13T23:42:57.367781909Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 13 23:42:57.368374 containerd[1459]: time="2025-05-13T23:42:57.367973229Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 13 23:42:57.368374 containerd[1459]: time="2025-05-13T23:42:57.368001709Z" 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 May 13 23:42:57.368374 containerd[1459]: time="2025-05-13T23:42:57.368011309Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 13 23:42:57.369360 containerd[1459]: time="2025-05-13T23:42:57.369328189Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 13 23:42:57.369814 containerd[1459]: time="2025-05-13T23:42:57.369791709Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 13 23:42:57.369957 containerd[1459]: time="2025-05-13T23:42:57.369934749Z" level=info msg="metadata content store policy set" policy=shared May 13 23:42:57.377138 containerd[1459]: time="2025-05-13T23:42:57.377106789Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 13 23:42:57.377272 containerd[1459]: time="2025-05-13T23:42:57.377252869Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 13 23:42:57.377357 containerd[1459]: time="2025-05-13T23:42:57.377340589Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 13 23:42:57.377413 containerd[1459]: time="2025-05-13T23:42:57.377398989Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 13 23:42:57.377466 containerd[1459]: time="2025-05-13T23:42:57.377451149Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 13 23:42:57.377539 containerd[1459]: time="2025-05-13T23:42:57.377521069Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 13 23:42:57.377594 containerd[1459]: time="2025-05-13T23:42:57.377580469Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 13 23:42:57.377668 containerd[1459]: time="2025-05-13T23:42:57.377654189Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 13 23:42:57.377738 containerd[1459]: time="2025-05-13T23:42:57.377721269Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 13 23:42:57.377808 containerd[1459]: time="2025-05-13T23:42:57.377793909Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 13 23:42:57.377868 containerd[1459]: time="2025-05-13T23:42:57.377854029Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 13 23:42:57.377923 containerd[1459]: time="2025-05-13T23:42:57.377909829Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 13 23:42:57.378100 containerd[1459]: time="2025-05-13T23:42:57.378078589Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 13 23:42:57.378175 containerd[1459]: time="2025-05-13T23:42:57.378159829Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 13 23:42:57.378230 containerd[1459]: time="2025-05-13T23:42:57.378216869Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 13 23:42:57.378285 containerd[1459]: time="2025-05-13T23:42:57.378271709Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 13 23:42:57.378356 containerd[1459]: time="2025-05-13T23:42:57.378341389Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 13 23:42:57.378411 containerd[1459]: time="2025-05-13T23:42:57.378397869Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 13 23:42:57.378468 containerd[1459]: time="2025-05-13T23:42:57.378455389Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 13 23:42:57.378546 containerd[1459]: time="2025-05-13T23:42:57.378530269Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 13 23:42:57.378688 containerd[1459]: time="2025-05-13T23:42:57.378669829Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 13 23:42:57.378746 containerd[1459]: time="2025-05-13T23:42:57.378732709Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 13 23:42:57.378799 containerd[1459]: time="2025-05-13T23:42:57.378785269Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 13 23:42:57.379286 containerd[1459]: time="2025-05-13T23:42:57.379268749Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 13 23:42:57.379340 containerd[1459]: time="2025-05-13T23:42:57.379327989Z" level=info msg="Start snapshots syncer" May 13 23:42:57.379427 containerd[1459]: time="2025-05-13T23:42:57.379410309Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 13 23:42:57.379813 containerd[1459]: time="2025-05-13T23:42:57.379772669Z" 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\"}" May 13 23:42:57.379978 containerd[1459]: time="2025-05-13T23:42:57.379958549Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 13 23:42:57.380171 containerd[1459]: time="2025-05-13T23:42:57.380118029Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 13 23:42:57.380372 containerd[1459]: time="2025-05-13T23:42:57.380348989Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 13 23:42:57.380449 containerd[1459]: time="2025-05-13T23:42:57.380435269Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 13 23:42:57.380501 containerd[1459]: time="2025-05-13T23:42:57.380488029Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 13 23:42:57.380586 containerd[1459]: time="2025-05-13T23:42:57.380570149Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 13 23:42:57.380660 containerd[1459]: time="2025-05-13T23:42:57.380645349Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 13 23:42:57.380713 containerd[1459]: time="2025-05-13T23:42:57.380700069Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 13 23:42:57.380765 containerd[1459]: time="2025-05-13T23:42:57.380752029Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 13 23:42:57.380853 containerd[1459]: time="2025-05-13T23:42:57.380839069Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 13 23:42:57.380911 containerd[1459]: time="2025-05-13T23:42:57.380897549Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 13 23:42:57.380979 containerd[1459]: time="2025-05-13T23:42:57.380964749Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 13 23:42:57.381081 containerd[1459]: time="2025-05-13T23:42:57.381063269Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 13 23:42:57.381161 containerd[1459]: time="2025-05-13T23:42:57.381126669Z" 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 May 13 23:42:57.381210 containerd[1459]: time="2025-05-13T23:42:57.381197309Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:42:57.381258 containerd[1459]: time="2025-05-13T23:42:57.381245389Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 13 23:42:57.381304 containerd[1459]: time="2025-05-13T23:42:57.381289949Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 13 23:42:57.381372 containerd[1459]: time="2025-05-13T23:42:57.381358429Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 13 23:42:57.381424 containerd[1459]: time="2025-05-13T23:42:57.381411429Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 13 23:42:57.381644 containerd[1459]: time="2025-05-13T23:42:57.381631269Z" level=info msg="runtime interface created" May 13 23:42:57.381687 containerd[1459]: time="2025-05-13T23:42:57.381677269Z" level=info msg="created NRI interface" May 13 23:42:57.381736 containerd[1459]: time="2025-05-13T23:42:57.381721909Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 13 23:42:57.381785 containerd[1459]: time="2025-05-13T23:42:57.381774309Z" level=info msg="Connect containerd service" May 13 23:42:57.381994 containerd[1459]: time="2025-05-13T23:42:57.381973149Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 13 23:42:57.384390 containerd[1459]: time="2025-05-13T23:42:57.384352549Z" 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" May 13 23:42:57.395418 sshd_keygen[1450]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 13 23:42:57.414773 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 13 23:42:57.418781 systemd[1]: Starting issuegen.service - Generate /run/issue... May 13 23:42:57.438835 systemd[1]: issuegen.service: Deactivated successfully. May 13 23:42:57.439087 systemd[1]: Finished issuegen.service - Generate /run/issue. May 13 23:42:57.442772 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 13 23:42:57.465161 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 13 23:42:57.469369 systemd[1]: Started getty@tty1.service - Getty on tty1. May 13 23:42:57.472450 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 13 23:42:57.474595 systemd[1]: Reached target getty.target - Login Prompts. May 13 23:42:57.494323 containerd[1459]: time="2025-05-13T23:42:57.494271789Z" level=info msg="Start subscribing containerd event" May 13 23:42:57.494448 containerd[1459]: time="2025-05-13T23:42:57.494336749Z" level=info msg="Start recovering state" May 13 23:42:57.494448 containerd[1459]: time="2025-05-13T23:42:57.494443429Z" level=info msg="Start event monitor" May 13 23:42:57.494484 containerd[1459]: time="2025-05-13T23:42:57.494459909Z" level=info msg="Start cni network conf syncer for default" May 13 23:42:57.494484 containerd[1459]: time="2025-05-13T23:42:57.494468429Z" level=info msg="Start streaming server" May 13 23:42:57.494484 containerd[1459]: time="2025-05-13T23:42:57.494477669Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 13 23:42:57.494541 containerd[1459]: time="2025-05-13T23:42:57.494485229Z" level=info msg="runtime interface starting up..." May 13 23:42:57.494541 containerd[1459]: time="2025-05-13T23:42:57.494491749Z" level=info msg="starting plugins..." May 13 23:42:57.494541 containerd[1459]: time="2025-05-13T23:42:57.494512869Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 13 23:42:57.494902 containerd[1459]: time="2025-05-13T23:42:57.494731589Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 13 23:42:57.494902 containerd[1459]: time="2025-05-13T23:42:57.494794309Z" level=info msg=serving... address=/run/containerd/containerd.sock May 13 23:42:57.494902 containerd[1459]: time="2025-05-13T23:42:57.494883589Z" level=info msg="containerd successfully booted in 0.140802s" May 13 23:42:57.495003 systemd[1]: Started containerd.service - containerd container runtime. May 13 23:42:58.004753 systemd-networkd[1385]: eth0: Gained IPv6LL May 13 23:42:58.008658 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 13 23:42:58.010735 systemd[1]: Reached target network-online.target - Network is Online. May 13 23:42:58.013925 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 13 23:42:58.016775 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:42:58.032699 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 13 23:42:58.053750 systemd[1]: coreos-metadata.service: Deactivated successfully. May 13 23:42:58.055193 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 13 23:42:58.057214 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 13 23:42:58.059901 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 13 23:42:58.639430 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:42:58.641361 systemd[1]: Reached target multi-user.target - Multi-User System. May 13 23:42:58.643298 systemd[1]: Startup finished in 600ms (kernel) + 4.697s (initrd) + 3.756s (userspace) = 9.054s. May 13 23:42:58.651046 (kubelet)[1551]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 13 23:42:59.168632 kubelet[1551]: E0513 23:42:59.168556 1551 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" May 13 23:42:59.171220 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 13 23:42:59.171373 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 13 23:42:59.171773 systemd[1]: kubelet.service: Consumed 870ms CPU time, 250.8M memory peak. May 13 23:43:02.878960 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 13 23:43:02.880356 systemd[1]: Started sshd@0-10.0.0.65:22-10.0.0.1:49128.service - OpenSSH per-connection server daemon (10.0.0.1:49128). May 13 23:43:02.953790 sshd[1565]: Accepted publickey for core from 10.0.0.1 port 49128 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:43:02.955693 sshd-session[1565]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:43:02.967137 systemd-logind[1442]: New session 1 of user core. May 13 23:43:02.968084 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 13 23:43:02.969123 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 13 23:43:03.001921 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 13 23:43:03.004027 systemd[1]: Starting user@500.service - User Manager for UID 500... May 13 23:43:03.016745 (systemd)[1569]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 13 23:43:03.019153 systemd-logind[1442]: New session c1 of user core. May 13 23:43:03.125103 systemd[1569]: Queued start job for default target default.target. May 13 23:43:03.139656 systemd[1569]: Created slice app.slice - User Application Slice. May 13 23:43:03.139688 systemd[1569]: Reached target paths.target - Paths. May 13 23:43:03.139726 systemd[1569]: Reached target timers.target - Timers. May 13 23:43:03.141024 systemd[1569]: Starting dbus.socket - D-Bus User Message Bus Socket... May 13 23:43:03.151651 systemd[1569]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 13 23:43:03.151815 systemd[1569]: Reached target sockets.target - Sockets. May 13 23:43:03.151862 systemd[1569]: Reached target basic.target - Basic System. May 13 23:43:03.151891 systemd[1569]: Reached target default.target - Main User Target. May 13 23:43:03.151918 systemd[1569]: Startup finished in 126ms. May 13 23:43:03.152080 systemd[1]: Started user@500.service - User Manager for UID 500. May 13 23:43:03.153685 systemd[1]: Started session-1.scope - Session 1 of User core. May 13 23:43:03.219961 systemd[1]: Started sshd@1-10.0.0.65:22-10.0.0.1:49142.service - OpenSSH per-connection server daemon (10.0.0.1:49142). May 13 23:43:03.279451 sshd[1580]: Accepted publickey for core from 10.0.0.1 port 49142 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:43:03.281016 sshd-session[1580]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:43:03.285337 systemd-logind[1442]: New session 2 of user core. May 13 23:43:03.295842 systemd[1]: Started session-2.scope - Session 2 of User core. May 13 23:43:03.347953 sshd[1582]: Connection closed by 10.0.0.1 port 49142 May 13 23:43:03.348304 sshd-session[1580]: pam_unix(sshd:session): session closed for user core May 13 23:43:03.364375 systemd[1]: sshd@1-10.0.0.65:22-10.0.0.1:49142.service: Deactivated successfully. May 13 23:43:03.366333 systemd[1]: session-2.scope: Deactivated successfully. May 13 23:43:03.367658 systemd-logind[1442]: Session 2 logged out. Waiting for processes to exit. May 13 23:43:03.368893 systemd[1]: Started sshd@2-10.0.0.65:22-10.0.0.1:49154.service - OpenSSH per-connection server daemon (10.0.0.1:49154). May 13 23:43:03.369698 systemd-logind[1442]: Removed session 2. May 13 23:43:03.411911 sshd[1587]: Accepted publickey for core from 10.0.0.1 port 49154 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:43:03.413195 sshd-session[1587]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:43:03.417539 systemd-logind[1442]: New session 3 of user core. May 13 23:43:03.423768 systemd[1]: Started session-3.scope - Session 3 of User core. May 13 23:43:03.472202 sshd[1590]: Connection closed by 10.0.0.1 port 49154 May 13 23:43:03.473278 sshd-session[1587]: pam_unix(sshd:session): session closed for user core May 13 23:43:03.492134 systemd[1]: sshd@2-10.0.0.65:22-10.0.0.1:49154.service: Deactivated successfully. May 13 23:43:03.493553 systemd[1]: session-3.scope: Deactivated successfully. May 13 23:43:03.495213 systemd-logind[1442]: Session 3 logged out. Waiting for processes to exit. May 13 23:43:03.496982 systemd[1]: Started sshd@3-10.0.0.65:22-10.0.0.1:49170.service - OpenSSH per-connection server daemon (10.0.0.1:49170). May 13 23:43:03.498074 systemd-logind[1442]: Removed session 3. May 13 23:43:03.550461 sshd[1595]: Accepted publickey for core from 10.0.0.1 port 49170 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:43:03.550921 sshd-session[1595]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:43:03.556895 systemd-logind[1442]: New session 4 of user core. May 13 23:43:03.566962 systemd[1]: Started session-4.scope - Session 4 of User core. May 13 23:43:03.622002 sshd[1598]: Connection closed by 10.0.0.1 port 49170 May 13 23:43:03.626989 sshd-session[1595]: pam_unix(sshd:session): session closed for user core May 13 23:43:03.637624 systemd[1]: sshd@3-10.0.0.65:22-10.0.0.1:49170.service: Deactivated successfully. May 13 23:43:03.639214 systemd[1]: session-4.scope: Deactivated successfully. May 13 23:43:03.640128 systemd-logind[1442]: Session 4 logged out. Waiting for processes to exit. May 13 23:43:03.643376 systemd[1]: Started sshd@4-10.0.0.65:22-10.0.0.1:49176.service - OpenSSH per-connection server daemon (10.0.0.1:49176). May 13 23:43:03.644094 systemd-logind[1442]: Removed session 4. May 13 23:43:03.710669 sshd[1603]: Accepted publickey for core from 10.0.0.1 port 49176 ssh2: RSA SHA256:mw68dZYQU0J8UXjv1qvX457MoBIWfYiH3KbOSP4fCfE May 13 23:43:03.712002 sshd-session[1603]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 13 23:43:03.716736 systemd-logind[1442]: New session 5 of user core. May 13 23:43:03.723828 systemd[1]: Started session-5.scope - Session 5 of User core. May 13 23:43:03.786684 sudo[1607]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 13 23:43:03.786986 sudo[1607]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 13 23:43:03.801140 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 13 23:43:03.843192 systemd[1]: coreos-metadata.service: Deactivated successfully. May 13 23:43:03.843475 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 13 23:43:04.361329 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:43:04.361478 systemd[1]: kubelet.service: Consumed 870ms CPU time, 250.8M memory peak. May 13 23:43:04.363590 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:43:04.387679 systemd[1]: Reload requested from client PID 1648 ('systemctl') (unit session-5.scope)... May 13 23:43:04.387709 systemd[1]: Reloading... May 13 23:43:04.470644 zram_generator::config[1693]: No configuration found. May 13 23:43:04.713782 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. May 13 23:43:04.803502 systemd[1]: Reloading finished in 415 ms. May 13 23:43:04.847922 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:43:04.849909 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:43:04.852717 systemd[1]: kubelet.service: Deactivated successfully. May 13 23:43:04.852960 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:43:04.853013 systemd[1]: kubelet.service: Consumed 100ms CPU time, 90.4M memory peak. May 13 23:43:04.855007 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 13 23:43:05.000387 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 13 23:43:05.015955 (kubelet)[1737]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 13 23:43:05.059932 kubelet[1737]: 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. May 13 23:43:05.059932 kubelet[1737]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. May 13 23:43:05.059932 kubelet[1737]: 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. May 13 23:43:05.060267 kubelet[1737]: I0513 23:43:05.060032 1737 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" May 13 23:43:05.573842 kubelet[1737]: I0513 23:43:05.573795 1737 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" May 13 23:43:05.573842 kubelet[1737]: I0513 23:43:05.573830 1737 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 13 23:43:05.574113 kubelet[1737]: I0513 23:43:05.574087 1737 server.go:954] "Client rotation is on, will bootstrap in background" May 13 23:43:05.619061 kubelet[1737]: I0513 23:43:05.619011 1737 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 13 23:43:05.638397 kubelet[1737]: I0513 23:43:05.638315 1737 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" May 13 23:43:05.641552 kubelet[1737]: I0513 23:43:05.641523 1737 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 13 23:43:05.642331 kubelet[1737]: I0513 23:43:05.642285 1737 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 13 23:43:05.642583 kubelet[1737]: I0513 23:43:05.642408 1737 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.65","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} May 13 23:43:05.642882 kubelet[1737]: I0513 23:43:05.642865 1737 topology_manager.go:138] "Creating topology manager with none policy" May 13 23:43:05.643386 kubelet[1737]: I0513 23:43:05.642928 1737 container_manager_linux.go:304] "Creating device plugin manager" May 13 23:43:05.643386 kubelet[1737]: I0513 23:43:05.643171 1737 state_mem.go:36] "Initialized new in-memory state store" May 13 23:43:05.645776 kubelet[1737]: I0513 23:43:05.645748 1737 kubelet.go:446] "Attempting to sync node with API server" May 13 23:43:05.645873 kubelet[1737]: I0513 23:43:05.645862 1737 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" May 13 23:43:05.645941 kubelet[1737]: I0513 23:43:05.645931 1737 kubelet.go:352] "Adding apiserver pod source" May 13 23:43:05.645987 kubelet[1737]: I0513 23:43:05.645979 1737 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 13 23:43:05.646176 kubelet[1737]: E0513 23:43:05.646144 1737 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:05.646176 kubelet[1737]: E0513 23:43:05.646174 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:05.654718 kubelet[1737]: W0513 23:43:05.654622 1737 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.65" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 13 23:43:05.654826 kubelet[1737]: E0513 23:43:05.654762 1737 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.65\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 13 23:43:05.654826 kubelet[1737]: W0513 23:43:05.654680 1737 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 May 13 23:43:05.654826 kubelet[1737]: E0513 23:43:05.654788 1737 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" May 13 23:43:05.655182 kubelet[1737]: I0513 23:43:05.655141 1737 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.1" apiVersion="v1" May 13 23:43:05.657704 kubelet[1737]: I0513 23:43:05.657681 1737 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 13 23:43:05.657814 kubelet[1737]: W0513 23:43:05.657801 1737 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 13 23:43:05.658709 kubelet[1737]: I0513 23:43:05.658675 1737 watchdog_linux.go:99] "Systemd watchdog is not enabled" May 13 23:43:05.658755 kubelet[1737]: I0513 23:43:05.658718 1737 server.go:1287] "Started kubelet" May 13 23:43:05.659714 kubelet[1737]: I0513 23:43:05.658823 1737 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 May 13 23:43:05.659869 kubelet[1737]: I0513 23:43:05.659846 1737 server.go:490] "Adding debug handlers to kubelet server" May 13 23:43:05.662303 kubelet[1737]: I0513 23:43:05.661830 1737 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 13 23:43:05.662367 kubelet[1737]: I0513 23:43:05.662333 1737 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 13 23:43:05.662512 kubelet[1737]: I0513 23:43:05.662475 1737 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 13 23:43:05.662947 kubelet[1737]: I0513 23:43:05.662892 1737 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 13 23:43:05.666822 kubelet[1737]: E0513 23:43:05.664389 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:05.666822 kubelet[1737]: I0513 23:43:05.664410 1737 volume_manager.go:297] "Starting Kubelet Volume Manager" May 13 23:43:05.666822 kubelet[1737]: I0513 23:43:05.664510 1737 desired_state_of_world_populator.go:149] "Desired state populator starts to run" May 13 23:43:05.666822 kubelet[1737]: I0513 23:43:05.664567 1737 reconciler.go:26] "Reconciler: start to sync state" May 13 23:43:05.669752 kubelet[1737]: E0513 23:43:05.669711 1737 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 13 23:43:05.672433 kubelet[1737]: I0513 23:43:05.672385 1737 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 May 13 23:43:05.674122 kubelet[1737]: I0513 23:43:05.674085 1737 factory.go:221] Registration of the containerd container factory successfully May 13 23:43:05.674122 kubelet[1737]: I0513 23:43:05.674116 1737 factory.go:221] Registration of the systemd container factory successfully May 13 23:43:05.686405 kubelet[1737]: E0513 23:43:05.686333 1737 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.65\" not found" node="10.0.0.65" May 13 23:43:05.689405 kubelet[1737]: I0513 23:43:05.689366 1737 cpu_manager.go:221] "Starting CPU manager" policy="none" May 13 23:43:05.689405 kubelet[1737]: I0513 23:43:05.689384 1737 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" May 13 23:43:05.689405 kubelet[1737]: I0513 23:43:05.689406 1737 state_mem.go:36] "Initialized new in-memory state store" May 13 23:43:05.759561 kubelet[1737]: I0513 23:43:05.759528 1737 policy_none.go:49] "None policy: Start" May 13 23:43:05.759561 kubelet[1737]: I0513 23:43:05.759559 1737 memory_manager.go:186] "Starting memorymanager" policy="None" May 13 23:43:05.759561 kubelet[1737]: I0513 23:43:05.759571 1737 state_mem.go:35] "Initializing new in-memory state store" May 13 23:43:05.764989 kubelet[1737]: E0513 23:43:05.764958 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:05.768882 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 13 23:43:05.783012 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 13 23:43:05.786091 kubelet[1737]: I0513 23:43:05.786049 1737 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 13 23:43:05.786168 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 13 23:43:05.787004 kubelet[1737]: I0513 23:43:05.786982 1737 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 13 23:43:05.787004 kubelet[1737]: I0513 23:43:05.787006 1737 status_manager.go:227] "Starting to sync pod status with apiserver" May 13 23:43:05.787104 kubelet[1737]: I0513 23:43:05.787027 1737 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." May 13 23:43:05.787104 kubelet[1737]: I0513 23:43:05.787033 1737 kubelet.go:2388] "Starting kubelet main sync loop" May 13 23:43:05.787104 kubelet[1737]: E0513 23:43:05.787077 1737 kubelet.go:2412] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" May 13 23:43:05.795209 kubelet[1737]: I0513 23:43:05.794629 1737 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 13 23:43:05.795209 kubelet[1737]: I0513 23:43:05.794823 1737 eviction_manager.go:189] "Eviction manager: starting control loop" May 13 23:43:05.795209 kubelet[1737]: I0513 23:43:05.794834 1737 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 13 23:43:05.795209 kubelet[1737]: I0513 23:43:05.795158 1737 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 13 23:43:05.796203 kubelet[1737]: E0513 23:43:05.796166 1737 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" May 13 23:43:05.796203 kubelet[1737]: E0513 23:43:05.796206 1737 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.65\" not found" May 13 23:43:05.896690 kubelet[1737]: I0513 23:43:05.896520 1737 kubelet_node_status.go:76] "Attempting to register node" node="10.0.0.65" May 13 23:43:05.901478 kubelet[1737]: I0513 23:43:05.901435 1737 kubelet_node_status.go:79] "Successfully registered node" node="10.0.0.65" May 13 23:43:05.901478 kubelet[1737]: E0513 23:43:05.901471 1737 kubelet_node_status.go:549] "Error updating node status, will retry" err="error getting node \"10.0.0.65\": node \"10.0.0.65\" not found" May 13 23:43:05.912174 kubelet[1737]: E0513 23:43:05.912111 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.013202 kubelet[1737]: E0513 23:43:06.013152 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.030399 sudo[1607]: pam_unix(sudo:session): session closed for user root May 13 23:43:06.031958 sshd[1606]: Connection closed by 10.0.0.1 port 49176 May 13 23:43:06.032390 sshd-session[1603]: pam_unix(sshd:session): session closed for user core May 13 23:43:06.036144 systemd[1]: sshd@4-10.0.0.65:22-10.0.0.1:49176.service: Deactivated successfully. May 13 23:43:06.037967 systemd[1]: session-5.scope: Deactivated successfully. May 13 23:43:06.038217 systemd[1]: session-5.scope: Consumed 482ms CPU time, 77.2M memory peak. May 13 23:43:06.039139 systemd-logind[1442]: Session 5 logged out. Waiting for processes to exit. May 13 23:43:06.040122 systemd-logind[1442]: Removed session 5. May 13 23:43:06.113718 kubelet[1737]: E0513 23:43:06.113671 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.214785 kubelet[1737]: E0513 23:43:06.214669 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.315382 kubelet[1737]: E0513 23:43:06.315321 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.415899 kubelet[1737]: E0513 23:43:06.415858 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.516631 kubelet[1737]: E0513 23:43:06.516484 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.576060 kubelet[1737]: I0513 23:43:06.576013 1737 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 13 23:43:06.576234 kubelet[1737]: W0513 23:43:06.576178 1737 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 May 13 23:43:06.576234 kubelet[1737]: W0513 23:43:06.576215 1737 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 13 23:43:06.617300 kubelet[1737]: E0513 23:43:06.617256 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.647692 kubelet[1737]: E0513 23:43:06.646451 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:06.718080 kubelet[1737]: E0513 23:43:06.718025 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.818365 kubelet[1737]: E0513 23:43:06.818318 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:06.918912 kubelet[1737]: E0513 23:43:06.918879 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:07.019451 kubelet[1737]: E0513 23:43:07.019420 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:07.120421 kubelet[1737]: E0513 23:43:07.120306 1737 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.65\" not found" May 13 23:43:07.221461 kubelet[1737]: I0513 23:43:07.221403 1737 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 13 23:43:07.221827 containerd[1459]: time="2025-05-13T23:43:07.221723509Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 13 23:43:07.222435 kubelet[1737]: I0513 23:43:07.222252 1737 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 13 23:43:07.647607 kubelet[1737]: E0513 23:43:07.647560 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:07.648804 kubelet[1737]: I0513 23:43:07.648766 1737 apiserver.go:52] "Watching apiserver" May 13 23:43:07.665426 kubelet[1737]: I0513 23:43:07.664774 1737 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" May 13 23:43:07.666210 systemd[1]: Created slice kubepods-burstable-podbea1fea8_b174_4b73_8147_bfaec07c8f3f.slice - libcontainer container kubepods-burstable-podbea1fea8_b174_4b73_8147_bfaec07c8f3f.slice. May 13 23:43:07.680031 kubelet[1737]: I0513 23:43:07.679986 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/f7efe8c5-5728-475b-acbd-741ffa4ff3f8-kube-proxy\") pod \"kube-proxy-h226v\" (UID: \"f7efe8c5-5728-475b-acbd-741ffa4ff3f8\") " pod="kube-system/kube-proxy-h226v" May 13 23:43:07.680031 kubelet[1737]: I0513 23:43:07.680027 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gb64n\" (UniqueName: \"kubernetes.io/projected/f7efe8c5-5728-475b-acbd-741ffa4ff3f8-kube-api-access-gb64n\") pod \"kube-proxy-h226v\" (UID: \"f7efe8c5-5728-475b-acbd-741ffa4ff3f8\") " pod="kube-system/kube-proxy-h226v" May 13 23:43:07.680182 kubelet[1737]: I0513 23:43:07.680052 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f7efe8c5-5728-475b-acbd-741ffa4ff3f8-xtables-lock\") pod \"kube-proxy-h226v\" (UID: \"f7efe8c5-5728-475b-acbd-741ffa4ff3f8\") " pod="kube-system/kube-proxy-h226v" May 13 23:43:07.680182 kubelet[1737]: I0513 23:43:07.680069 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/f7efe8c5-5728-475b-acbd-741ffa4ff3f8-lib-modules\") pod \"kube-proxy-h226v\" (UID: \"f7efe8c5-5728-475b-acbd-741ffa4ff3f8\") " pod="kube-system/kube-proxy-h226v" May 13 23:43:07.680182 kubelet[1737]: I0513 23:43:07.680084 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/bea1fea8-b174-4b73-8147-bfaec07c8f3f-run\") pod \"kube-flannel-ds-sbhdt\" (UID: \"bea1fea8-b174-4b73-8147-bfaec07c8f3f\") " pod="kube-flannel/kube-flannel-ds-sbhdt" May 13 23:43:07.680182 kubelet[1737]: I0513 23:43:07.680115 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/bea1fea8-b174-4b73-8147-bfaec07c8f3f-cni-plugin\") pod \"kube-flannel-ds-sbhdt\" (UID: \"bea1fea8-b174-4b73-8147-bfaec07c8f3f\") " pod="kube-flannel/kube-flannel-ds-sbhdt" May 13 23:43:07.680182 kubelet[1737]: I0513 23:43:07.680131 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/bea1fea8-b174-4b73-8147-bfaec07c8f3f-cni\") pod \"kube-flannel-ds-sbhdt\" (UID: \"bea1fea8-b174-4b73-8147-bfaec07c8f3f\") " pod="kube-flannel/kube-flannel-ds-sbhdt" May 13 23:43:07.680292 kubelet[1737]: I0513 23:43:07.680149 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/bea1fea8-b174-4b73-8147-bfaec07c8f3f-flannel-cfg\") pod \"kube-flannel-ds-sbhdt\" (UID: \"bea1fea8-b174-4b73-8147-bfaec07c8f3f\") " pod="kube-flannel/kube-flannel-ds-sbhdt" May 13 23:43:07.680292 kubelet[1737]: I0513 23:43:07.680164 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/bea1fea8-b174-4b73-8147-bfaec07c8f3f-xtables-lock\") pod \"kube-flannel-ds-sbhdt\" (UID: \"bea1fea8-b174-4b73-8147-bfaec07c8f3f\") " pod="kube-flannel/kube-flannel-ds-sbhdt" May 13 23:43:07.680292 kubelet[1737]: I0513 23:43:07.680205 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mn62m\" (UniqueName: \"kubernetes.io/projected/bea1fea8-b174-4b73-8147-bfaec07c8f3f-kube-api-access-mn62m\") pod \"kube-flannel-ds-sbhdt\" (UID: \"bea1fea8-b174-4b73-8147-bfaec07c8f3f\") " pod="kube-flannel/kube-flannel-ds-sbhdt" May 13 23:43:07.689244 systemd[1]: Created slice kubepods-besteffort-podf7efe8c5_5728_475b_acbd_741ffa4ff3f8.slice - libcontainer container kubepods-besteffort-podf7efe8c5_5728_475b_acbd_741ffa4ff3f8.slice. May 13 23:43:07.987978 containerd[1459]: time="2025-05-13T23:43:07.987837749Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-sbhdt,Uid:bea1fea8-b174-4b73-8147-bfaec07c8f3f,Namespace:kube-flannel,Attempt:0,}" May 13 23:43:08.001565 containerd[1459]: time="2025-05-13T23:43:08.001202669Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-h226v,Uid:f7efe8c5-5728-475b-acbd-741ffa4ff3f8,Namespace:kube-system,Attempt:0,}" May 13 23:43:08.583564 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1761390347.mount: Deactivated successfully. May 13 23:43:08.594854 containerd[1459]: time="2025-05-13T23:43:08.594795309Z" 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\"}" May 13 23:43:08.596013 containerd[1459]: time="2025-05-13T23:43:08.595978269Z" 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\"}" May 13 23:43:08.596673 containerd[1459]: time="2025-05-13T23:43:08.596607189Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" May 13 23:43:08.597512 containerd[1459]: time="2025-05-13T23:43:08.597447629Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" May 13 23:43:08.598187 containerd[1459]: time="2025-05-13T23:43:08.598137029Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 13 23:43:08.601623 containerd[1459]: time="2025-05-13T23:43:08.600164989Z" 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\"}" May 13 23:43:08.601623 containerd[1459]: time="2025-05-13T23:43:08.601391909Z" 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 595.8132ms" May 13 23:43:08.602408 containerd[1459]: time="2025-05-13T23:43:08.602377789Z" 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 605.784ms" May 13 23:43:08.634496 containerd[1459]: time="2025-05-13T23:43:08.633742829Z" level=info msg="connecting to shim f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98" address="unix:///run/containerd/s/827e3af278b2600633b832a6577a47144d1470fd77ff770c114728c948f87aa6" namespace=k8s.io protocol=ttrpc version=3 May 13 23:43:08.635434 containerd[1459]: time="2025-05-13T23:43:08.635389709Z" level=info msg="connecting to shim c9543b69bfe49bb9cd2f4ede8eeb19febb8abbad1416bcd76a946b91c32b1949" address="unix:///run/containerd/s/0e4d8927ff7a237f5d7183a45b2edf13ff49c7706441c2d1975b2bad95a8a05b" namespace=k8s.io protocol=ttrpc version=3 May 13 23:43:08.648585 kubelet[1737]: E0513 23:43:08.648555 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:08.660846 systemd[1]: Started cri-containerd-f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98.scope - libcontainer container f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98. May 13 23:43:08.664137 systemd[1]: Started cri-containerd-c9543b69bfe49bb9cd2f4ede8eeb19febb8abbad1416bcd76a946b91c32b1949.scope - libcontainer container c9543b69bfe49bb9cd2f4ede8eeb19febb8abbad1416bcd76a946b91c32b1949. May 13 23:43:08.694295 containerd[1459]: time="2025-05-13T23:43:08.694246109Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-h226v,Uid:f7efe8c5-5728-475b-acbd-741ffa4ff3f8,Namespace:kube-system,Attempt:0,} returns sandbox id \"c9543b69bfe49bb9cd2f4ede8eeb19febb8abbad1416bcd76a946b91c32b1949\"" May 13 23:43:08.696819 containerd[1459]: time="2025-05-13T23:43:08.696784709Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\"" May 13 23:43:08.705361 containerd[1459]: time="2025-05-13T23:43:08.705318029Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-sbhdt,Uid:bea1fea8-b174-4b73-8147-bfaec07c8f3f,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98\"" May 13 23:43:09.647184 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1359975970.mount: Deactivated successfully. May 13 23:43:09.649407 kubelet[1737]: E0513 23:43:09.649367 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:09.888534 containerd[1459]: time="2025-05-13T23:43:09.888478509Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:09.889487 containerd[1459]: time="2025-05-13T23:43:09.889273829Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.4: active requests=0, bytes read=27370353" May 13 23:43:09.890404 containerd[1459]: time="2025-05-13T23:43:09.890364029Z" level=info msg="ImageCreate event name:\"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:09.892442 containerd[1459]: time="2025-05-13T23:43:09.892400189Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:09.893515 containerd[1459]: time="2025-05-13T23:43:09.893477509Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.4\" with image id \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\", repo tag \"registry.k8s.io/kube-proxy:v1.32.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\", size \"27369370\" in 1.19665092s" May 13 23:43:09.893781 containerd[1459]: time="2025-05-13T23:43:09.893622029Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\" returns image reference \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\"" May 13 23:43:09.894693 containerd[1459]: time="2025-05-13T23:43:09.894663549Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 13 23:43:09.896153 containerd[1459]: time="2025-05-13T23:43:09.896111109Z" level=info msg="CreateContainer within sandbox \"c9543b69bfe49bb9cd2f4ede8eeb19febb8abbad1416bcd76a946b91c32b1949\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 13 23:43:09.911306 containerd[1459]: time="2025-05-13T23:43:09.911192829Z" level=info msg="Container 373c6ba722ff3dc5c8c7c2d9ba685fe3f08ed65820efa86fe9241d7fa1492de2: CDI devices from CRI Config.CDIDevices: []" May 13 23:43:09.922051 containerd[1459]: time="2025-05-13T23:43:09.921990029Z" level=info msg="CreateContainer within sandbox \"c9543b69bfe49bb9cd2f4ede8eeb19febb8abbad1416bcd76a946b91c32b1949\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"373c6ba722ff3dc5c8c7c2d9ba685fe3f08ed65820efa86fe9241d7fa1492de2\"" May 13 23:43:09.923133 containerd[1459]: time="2025-05-13T23:43:09.923091589Z" level=info msg="StartContainer for \"373c6ba722ff3dc5c8c7c2d9ba685fe3f08ed65820efa86fe9241d7fa1492de2\"" May 13 23:43:09.924765 containerd[1459]: time="2025-05-13T23:43:09.924725869Z" level=info msg="connecting to shim 373c6ba722ff3dc5c8c7c2d9ba685fe3f08ed65820efa86fe9241d7fa1492de2" address="unix:///run/containerd/s/0e4d8927ff7a237f5d7183a45b2edf13ff49c7706441c2d1975b2bad95a8a05b" protocol=ttrpc version=3 May 13 23:43:09.944833 systemd[1]: Started cri-containerd-373c6ba722ff3dc5c8c7c2d9ba685fe3f08ed65820efa86fe9241d7fa1492de2.scope - libcontainer container 373c6ba722ff3dc5c8c7c2d9ba685fe3f08ed65820efa86fe9241d7fa1492de2. May 13 23:43:09.977675 containerd[1459]: time="2025-05-13T23:43:09.977627269Z" level=info msg="StartContainer for \"373c6ba722ff3dc5c8c7c2d9ba685fe3f08ed65820efa86fe9241d7fa1492de2\" returns successfully" May 13 23:43:10.650556 kubelet[1737]: E0513 23:43:10.650509 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:10.818721 kubelet[1737]: I0513 23:43:10.818533 1737 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-h226v" podStartSLOduration=4.620345709 podStartE2EDuration="5.818515029s" podCreationTimestamp="2025-05-13 23:43:05 +0000 UTC" firstStartedPulling="2025-05-13 23:43:08.696354829 +0000 UTC m=+3.676640041" lastFinishedPulling="2025-05-13 23:43:09.894524149 +0000 UTC m=+4.874809361" observedRunningTime="2025-05-13 23:43:10.818475109 +0000 UTC m=+5.798760321" watchObservedRunningTime="2025-05-13 23:43:10.818515029 +0000 UTC m=+5.798800241" May 13 23:43:11.225373 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2843338434.mount: Deactivated successfully. May 13 23:43:11.262418 containerd[1459]: time="2025-05-13T23:43:11.262196389Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:11.263212 containerd[1459]: time="2025-05-13T23:43:11.262985549Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" May 13 23:43:11.263877 containerd[1459]: time="2025-05-13T23:43:11.263840429Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:11.266808 containerd[1459]: time="2025-05-13T23:43:11.266748069Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:11.267927 containerd[1459]: time="2025-05-13T23:43:11.267683709Z" 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.37298464s" May 13 23:43:11.267927 containerd[1459]: time="2025-05-13T23:43:11.267722629Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 13 23:43:11.270058 containerd[1459]: time="2025-05-13T23:43:11.269667509Z" level=info msg="CreateContainer within sandbox \"f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 13 23:43:11.276352 containerd[1459]: time="2025-05-13T23:43:11.276313549Z" level=info msg="Container fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0: CDI devices from CRI Config.CDIDevices: []" May 13 23:43:11.284899 containerd[1459]: time="2025-05-13T23:43:11.284839269Z" level=info msg="CreateContainer within sandbox \"f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0\"" May 13 23:43:11.285873 containerd[1459]: time="2025-05-13T23:43:11.285813109Z" level=info msg="StartContainer for \"fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0\"" May 13 23:43:11.286719 containerd[1459]: time="2025-05-13T23:43:11.286691549Z" level=info msg="connecting to shim fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0" address="unix:///run/containerd/s/827e3af278b2600633b832a6577a47144d1470fd77ff770c114728c948f87aa6" protocol=ttrpc version=3 May 13 23:43:11.310994 systemd[1]: Started cri-containerd-fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0.scope - libcontainer container fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0. May 13 23:43:11.349829 systemd[1]: cri-containerd-fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0.scope: Deactivated successfully. May 13 23:43:11.350908 containerd[1459]: time="2025-05-13T23:43:11.350855509Z" level=info msg="StartContainer for \"fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0\" returns successfully" May 13 23:43:11.351922 containerd[1459]: time="2025-05-13T23:43:11.351867509Z" level=info msg="received exit event container_id:\"fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0\" id:\"fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0\" pid:2068 exited_at:{seconds:1747179791 nanos:350693469}" May 13 23:43:11.352058 containerd[1459]: time="2025-05-13T23:43:11.352022629Z" level=info msg="TaskExit event in podsandbox handler container_id:\"fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0\" id:\"fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0\" pid:2068 exited_at:{seconds:1747179791 nanos:350693469}" May 13 23:43:11.651144 kubelet[1737]: E0513 23:43:11.651091 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:11.807945 containerd[1459]: time="2025-05-13T23:43:11.807878469Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 13 23:43:12.163948 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-fb602abe19e19ad54a7c9c6307dce90ae0577318c4a9d3a0cdc144540ad531a0-rootfs.mount: Deactivated successfully. May 13 23:43:12.651730 kubelet[1737]: E0513 23:43:12.651663 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:12.983653 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4167394288.mount: Deactivated successfully. May 13 23:43:13.622004 containerd[1459]: time="2025-05-13T23:43:13.621933509Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:13.622955 containerd[1459]: time="2025-05-13T23:43:13.622896549Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" May 13 23:43:13.623804 containerd[1459]: time="2025-05-13T23:43:13.623766149Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:13.626803 containerd[1459]: time="2025-05-13T23:43:13.626762549Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:13.628157 containerd[1459]: time="2025-05-13T23:43:13.628115149Z" 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.82019348s" May 13 23:43:13.628216 containerd[1459]: time="2025-05-13T23:43:13.628159669Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 13 23:43:13.630304 containerd[1459]: time="2025-05-13T23:43:13.630265909Z" level=info msg="CreateContainer within sandbox \"f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 13 23:43:13.639140 containerd[1459]: time="2025-05-13T23:43:13.638379749Z" level=info msg="Container ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092: CDI devices from CRI Config.CDIDevices: []" May 13 23:43:13.648295 containerd[1459]: time="2025-05-13T23:43:13.648247949Z" level=info msg="CreateContainer within sandbox \"f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092\"" May 13 23:43:13.648895 containerd[1459]: time="2025-05-13T23:43:13.648868069Z" level=info msg="StartContainer for \"ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092\"" May 13 23:43:13.649856 containerd[1459]: time="2025-05-13T23:43:13.649822749Z" level=info msg="connecting to shim ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092" address="unix:///run/containerd/s/827e3af278b2600633b832a6577a47144d1470fd77ff770c114728c948f87aa6" protocol=ttrpc version=3 May 13 23:43:13.652352 kubelet[1737]: E0513 23:43:13.652317 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:13.670801 systemd[1]: Started cri-containerd-ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092.scope - libcontainer container ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092. May 13 23:43:13.705399 containerd[1459]: time="2025-05-13T23:43:13.705344069Z" level=info msg="StartContainer for \"ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092\" returns successfully" May 13 23:43:13.706117 systemd[1]: cri-containerd-ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092.scope: Deactivated successfully. May 13 23:43:13.707480 containerd[1459]: time="2025-05-13T23:43:13.707439309Z" level=info msg="received exit event container_id:\"ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092\" id:\"ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092\" pid:2141 exited_at:{seconds:1747179793 nanos:707179429}" May 13 23:43:13.707905 containerd[1459]: time="2025-05-13T23:43:13.707881069Z" level=info msg="TaskExit event in podsandbox handler container_id:\"ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092\" id:\"ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092\" pid:2141 exited_at:{seconds:1747179793 nanos:707179429}" May 13 23:43:13.726752 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-ba94d92de95cfc7ae031bdf624e325ca230ecdb660bc66455059daf4c3d8d092-rootfs.mount: Deactivated successfully. May 13 23:43:13.752473 kubelet[1737]: I0513 23:43:13.752401 1737 kubelet_node_status.go:502] "Fast updating node status as it just became ready" May 13 23:43:14.653152 kubelet[1737]: E0513 23:43:14.653088 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:14.823483 containerd[1459]: time="2025-05-13T23:43:14.823431269Z" level=info msg="CreateContainer within sandbox \"f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 13 23:43:14.832309 containerd[1459]: time="2025-05-13T23:43:14.831884989Z" level=info msg="Container acf662ba4ef03e359507cd40e308b05e569c4b3c23d385f2ba28729bc418c358: CDI devices from CRI Config.CDIDevices: []" May 13 23:43:14.839575 containerd[1459]: time="2025-05-13T23:43:14.839516989Z" level=info msg="CreateContainer within sandbox \"f94f311adf26e09a9b38de6bab5f42fb9c01a28bcf7bb17720fdcae199cc1a98\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"acf662ba4ef03e359507cd40e308b05e569c4b3c23d385f2ba28729bc418c358\"" May 13 23:43:14.840044 containerd[1459]: time="2025-05-13T23:43:14.840024069Z" level=info msg="StartContainer for \"acf662ba4ef03e359507cd40e308b05e569c4b3c23d385f2ba28729bc418c358\"" May 13 23:43:14.840910 containerd[1459]: time="2025-05-13T23:43:14.840882349Z" level=info msg="connecting to shim acf662ba4ef03e359507cd40e308b05e569c4b3c23d385f2ba28729bc418c358" address="unix:///run/containerd/s/827e3af278b2600633b832a6577a47144d1470fd77ff770c114728c948f87aa6" protocol=ttrpc version=3 May 13 23:43:14.859856 systemd[1]: Started cri-containerd-acf662ba4ef03e359507cd40e308b05e569c4b3c23d385f2ba28729bc418c358.scope - libcontainer container acf662ba4ef03e359507cd40e308b05e569c4b3c23d385f2ba28729bc418c358. May 13 23:43:14.889878 containerd[1459]: time="2025-05-13T23:43:14.889833229Z" level=info msg="StartContainer for \"acf662ba4ef03e359507cd40e308b05e569c4b3c23d385f2ba28729bc418c358\" returns successfully" May 13 23:43:15.653511 kubelet[1737]: E0513 23:43:15.653454 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:15.985342 systemd-networkd[1385]: flannel.1: Link UP May 13 23:43:15.985350 systemd-networkd[1385]: flannel.1: Gained carrier May 13 23:43:16.654619 kubelet[1737]: E0513 23:43:16.654546 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:17.035380 kubelet[1737]: I0513 23:43:17.035142 1737 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-sbhdt" podStartSLOduration=7.112799629 podStartE2EDuration="12.035113069s" podCreationTimestamp="2025-05-13 23:43:05 +0000 UTC" firstStartedPulling="2025-05-13 23:43:08.706730509 +0000 UTC m=+3.687015721" lastFinishedPulling="2025-05-13 23:43:13.629043949 +0000 UTC m=+8.609329161" observedRunningTime="2025-05-13 23:43:15.837917589 +0000 UTC m=+10.818202801" watchObservedRunningTime="2025-05-13 23:43:17.035113069 +0000 UTC m=+12.015398281" May 13 23:43:17.042624 systemd[1]: Created slice kubepods-besteffort-pod2d51506e_4319_4a5a_9bab_672e59ebcbc9.slice - libcontainer container kubepods-besteffort-pod2d51506e_4319_4a5a_9bab_672e59ebcbc9.slice. May 13 23:43:17.140876 kubelet[1737]: I0513 23:43:17.140782 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x4dpx\" (UniqueName: \"kubernetes.io/projected/2d51506e-4319-4a5a-9bab-672e59ebcbc9-kube-api-access-x4dpx\") pod \"nginx-deployment-7fcdb87857-nhrvq\" (UID: \"2d51506e-4319-4a5a-9bab-672e59ebcbc9\") " pod="default/nginx-deployment-7fcdb87857-nhrvq" May 13 23:43:17.347631 containerd[1459]: time="2025-05-13T23:43:17.347508389Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-nhrvq,Uid:2d51506e-4319-4a5a-9bab-672e59ebcbc9,Namespace:default,Attempt:0,}" May 13 23:43:17.423770 systemd-networkd[1385]: cni0: Link UP May 13 23:43:17.429696 systemd-networkd[1385]: veth78f3253e: Link UP May 13 23:43:17.431271 kernel: cni0: port 1(veth78f3253e) entered blocking state May 13 23:43:17.431332 kernel: cni0: port 1(veth78f3253e) entered disabled state May 13 23:43:17.431976 kernel: veth78f3253e: entered allmulticast mode May 13 23:43:17.432693 kernel: veth78f3253e: entered promiscuous mode May 13 23:43:17.434905 kernel: cni0: port 1(veth78f3253e) entered blocking state May 13 23:43:17.434955 kernel: cni0: port 1(veth78f3253e) entered forwarding state May 13 23:43:17.436627 kernel: cni0: port 1(veth78f3253e) entered disabled state May 13 23:43:17.459923 kernel: cni0: port 1(veth78f3253e) entered blocking state May 13 23:43:17.460025 kernel: cni0: port 1(veth78f3253e) entered forwarding state May 13 23:43:17.459778 systemd-networkd[1385]: veth78f3253e: Gained carrier May 13 23:43:17.460207 systemd-networkd[1385]: cni0: Gained carrier May 13 23:43:17.465195 containerd[1459]: 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"} May 13 23:43:17.465195 containerd[1459]: delegateAdd: netconf sent to delegate plugin: May 13 23:43:17.513532 containerd[1459]: {"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-05-13T23:43:17.513470269Z" level=info msg="connecting to shim 1ba58ef40e783461cbd7e9794ce82bc513979ef0a3c86de23e896b6d4a3f45a5" address="unix:///run/containerd/s/ce477826167fb3eca2c727b6181ed1733f0033585b2f7098822f390b83622123" namespace=k8s.io protocol=ttrpc version=3 May 13 23:43:17.551812 systemd[1]: Started cri-containerd-1ba58ef40e783461cbd7e9794ce82bc513979ef0a3c86de23e896b6d4a3f45a5.scope - libcontainer container 1ba58ef40e783461cbd7e9794ce82bc513979ef0a3c86de23e896b6d4a3f45a5. May 13 23:43:17.562681 systemd-resolved[1325]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 23:43:17.585239 containerd[1459]: time="2025-05-13T23:43:17.585189269Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-nhrvq,Uid:2d51506e-4319-4a5a-9bab-672e59ebcbc9,Namespace:default,Attempt:0,} returns sandbox id \"1ba58ef40e783461cbd7e9794ce82bc513979ef0a3c86de23e896b6d4a3f45a5\"" May 13 23:43:17.586860 containerd[1459]: time="2025-05-13T23:43:17.586653989Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 13 23:43:17.655791 kubelet[1737]: E0513 23:43:17.655670 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:17.780816 systemd-networkd[1385]: flannel.1: Gained IPv6LL May 13 23:43:18.656422 kubelet[1737]: E0513 23:43:18.656343 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:18.932833 systemd-networkd[1385]: cni0: Gained IPv6LL May 13 23:43:19.381839 systemd-networkd[1385]: veth78f3253e: Gained IPv6LL May 13 23:43:19.577956 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount886242491.mount: Deactivated successfully. May 13 23:43:19.656725 kubelet[1737]: E0513 23:43:19.656550 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:20.386315 containerd[1459]: time="2025-05-13T23:43:20.386239149Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:20.388168 containerd[1459]: time="2025-05-13T23:43:20.387888869Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948859" May 13 23:43:20.389227 containerd[1459]: time="2025-05-13T23:43:20.389184269Z" level=info msg="ImageCreate event name:\"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:20.392593 containerd[1459]: time="2025-05-13T23:43:20.392552189Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:20.393635 containerd[1459]: time="2025-05-13T23:43:20.393552029Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"69948737\" in 2.80669084s" May 13 23:43:20.393635 containerd[1459]: time="2025-05-13T23:43:20.393588309Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 13 23:43:20.395633 containerd[1459]: time="2025-05-13T23:43:20.395553589Z" level=info msg="CreateContainer within sandbox \"1ba58ef40e783461cbd7e9794ce82bc513979ef0a3c86de23e896b6d4a3f45a5\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 13 23:43:20.403875 containerd[1459]: time="2025-05-13T23:43:20.403813909Z" level=info msg="Container e9b99314f0e33e09a45660e88bd22337bdccf4a2ddba6831615df5921eacdb4c: CDI devices from CRI Config.CDIDevices: []" May 13 23:43:20.408100 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1412602390.mount: Deactivated successfully. May 13 23:43:20.413265 containerd[1459]: time="2025-05-13T23:43:20.413209869Z" level=info msg="CreateContainer within sandbox \"1ba58ef40e783461cbd7e9794ce82bc513979ef0a3c86de23e896b6d4a3f45a5\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"e9b99314f0e33e09a45660e88bd22337bdccf4a2ddba6831615df5921eacdb4c\"" May 13 23:43:20.414024 containerd[1459]: time="2025-05-13T23:43:20.413861149Z" level=info msg="StartContainer for \"e9b99314f0e33e09a45660e88bd22337bdccf4a2ddba6831615df5921eacdb4c\"" May 13 23:43:20.414811 containerd[1459]: time="2025-05-13T23:43:20.414782869Z" level=info msg="connecting to shim e9b99314f0e33e09a45660e88bd22337bdccf4a2ddba6831615df5921eacdb4c" address="unix:///run/containerd/s/ce477826167fb3eca2c727b6181ed1733f0033585b2f7098822f390b83622123" protocol=ttrpc version=3 May 13 23:43:20.439823 systemd[1]: Started cri-containerd-e9b99314f0e33e09a45660e88bd22337bdccf4a2ddba6831615df5921eacdb4c.scope - libcontainer container e9b99314f0e33e09a45660e88bd22337bdccf4a2ddba6831615df5921eacdb4c. May 13 23:43:20.507728 containerd[1459]: time="2025-05-13T23:43:20.507452669Z" level=info msg="StartContainer for \"e9b99314f0e33e09a45660e88bd22337bdccf4a2ddba6831615df5921eacdb4c\" returns successfully" May 13 23:43:20.657229 kubelet[1737]: E0513 23:43:20.657089 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:21.657443 kubelet[1737]: E0513 23:43:21.657397 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:22.657778 kubelet[1737]: E0513 23:43:22.657721 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:23.657893 kubelet[1737]: E0513 23:43:23.657830 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:23.721684 kubelet[1737]: I0513 23:43:23.721257 1737 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-nhrvq" podStartSLOduration=3.913187709 podStartE2EDuration="6.721234949s" podCreationTimestamp="2025-05-13 23:43:17 +0000 UTC" firstStartedPulling="2025-05-13 23:43:17.586363709 +0000 UTC m=+12.566648921" lastFinishedPulling="2025-05-13 23:43:20.394410949 +0000 UTC m=+15.374696161" observedRunningTime="2025-05-13 23:43:20.846352429 +0000 UTC m=+15.826637601" watchObservedRunningTime="2025-05-13 23:43:23.721234949 +0000 UTC m=+18.701520161" May 13 23:43:23.727267 systemd[1]: Created slice kubepods-besteffort-podbe028a70_9f6b_41d6_88ac_75b37a8b0b80.slice - libcontainer container kubepods-besteffort-podbe028a70_9f6b_41d6_88ac_75b37a8b0b80.slice. May 13 23:43:23.781419 kubelet[1737]: I0513 23:43:23.781369 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kplzd\" (UniqueName: \"kubernetes.io/projected/be028a70-9f6b-41d6-88ac-75b37a8b0b80-kube-api-access-kplzd\") pod \"nfs-server-provisioner-0\" (UID: \"be028a70-9f6b-41d6-88ac-75b37a8b0b80\") " pod="default/nfs-server-provisioner-0" May 13 23:43:23.781419 kubelet[1737]: I0513 23:43:23.781423 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/be028a70-9f6b-41d6-88ac-75b37a8b0b80-data\") pod \"nfs-server-provisioner-0\" (UID: \"be028a70-9f6b-41d6-88ac-75b37a8b0b80\") " pod="default/nfs-server-provisioner-0" May 13 23:43:24.030314 containerd[1459]: time="2025-05-13T23:43:24.030199469Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:be028a70-9f6b-41d6-88ac-75b37a8b0b80,Namespace:default,Attempt:0,}" May 13 23:43:24.171194 systemd-networkd[1385]: veth4d3994ad: Link UP May 13 23:43:24.173644 kernel: cni0: port 2(veth4d3994ad) entered blocking state May 13 23:43:24.173758 kernel: cni0: port 2(veth4d3994ad) entered disabled state May 13 23:43:24.173784 kernel: veth4d3994ad: entered allmulticast mode May 13 23:43:24.173800 kernel: veth4d3994ad: entered promiscuous mode May 13 23:43:24.180771 kernel: cni0: port 2(veth4d3994ad) entered blocking state May 13 23:43:24.180842 kernel: cni0: port 2(veth4d3994ad) entered forwarding state May 13 23:43:24.180690 systemd-networkd[1385]: veth4d3994ad: Gained carrier May 13 23:43:24.185755 containerd[1459]: 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)(0x40001148e8), "name":"cbr0", "type":"bridge"} May 13 23:43:24.185755 containerd[1459]: delegateAdd: netconf sent to delegate plugin: May 13 23:43:24.640477 containerd[1459]: {"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-05-13T23:43:24.640425389Z" level=info msg="connecting to shim 5935dc41dcbcbee04a9fb7a35c07df088646ec6a9005f7dd66c3221ec058166f" address="unix:///run/containerd/s/37fbfd536c75798a7a17b08621f2183b0124c57fdd4ddb346af381e70569ea21" namespace=k8s.io protocol=ttrpc version=3 May 13 23:43:24.658414 kubelet[1737]: E0513 23:43:24.658356 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:24.672116 systemd[1]: Started cri-containerd-5935dc41dcbcbee04a9fb7a35c07df088646ec6a9005f7dd66c3221ec058166f.scope - libcontainer container 5935dc41dcbcbee04a9fb7a35c07df088646ec6a9005f7dd66c3221ec058166f. May 13 23:43:24.686838 systemd-resolved[1325]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 23:43:24.713260 containerd[1459]: time="2025-05-13T23:43:24.713137949Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:be028a70-9f6b-41d6-88ac-75b37a8b0b80,Namespace:default,Attempt:0,} returns sandbox id \"5935dc41dcbcbee04a9fb7a35c07df088646ec6a9005f7dd66c3221ec058166f\"" May 13 23:43:24.714956 containerd[1459]: time="2025-05-13T23:43:24.714893029Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 13 23:43:25.646771 kubelet[1737]: E0513 23:43:25.646702 1737 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:25.658680 kubelet[1737]: E0513 23:43:25.658553 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:25.717673 systemd-networkd[1385]: veth4d3994ad: Gained IPv6LL May 13 23:43:26.528888 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4053030726.mount: Deactivated successfully. May 13 23:43:26.659438 kubelet[1737]: E0513 23:43:26.659389 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:27.660274 kubelet[1737]: E0513 23:43:27.660216 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:28.090351 containerd[1459]: time="2025-05-13T23:43:28.089440549Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:28.090351 containerd[1459]: time="2025-05-13T23:43:28.090299509Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" May 13 23:43:28.090772 containerd[1459]: time="2025-05-13T23:43:28.090726829Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:28.093547 containerd[1459]: time="2025-05-13T23:43:28.093511949Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:28.095373 containerd[1459]: time="2025-05-13T23:43:28.095331429Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.38037952s" May 13 23:43:28.095422 containerd[1459]: time="2025-05-13T23:43:28.095381829Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 13 23:43:28.097662 containerd[1459]: time="2025-05-13T23:43:28.097540509Z" level=info msg="CreateContainer within sandbox \"5935dc41dcbcbee04a9fb7a35c07df088646ec6a9005f7dd66c3221ec058166f\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 13 23:43:28.106552 containerd[1459]: time="2025-05-13T23:43:28.105560269Z" level=info msg="Container ec82ead72423dcc2468e8b3fc2b0b67caaa8c4fe66b6f59231ca56999b83e710: CDI devices from CRI Config.CDIDevices: []" May 13 23:43:28.112544 containerd[1459]: time="2025-05-13T23:43:28.112485669Z" level=info msg="CreateContainer within sandbox \"5935dc41dcbcbee04a9fb7a35c07df088646ec6a9005f7dd66c3221ec058166f\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ec82ead72423dcc2468e8b3fc2b0b67caaa8c4fe66b6f59231ca56999b83e710\"" May 13 23:43:28.113191 containerd[1459]: time="2025-05-13T23:43:28.113145389Z" level=info msg="StartContainer for \"ec82ead72423dcc2468e8b3fc2b0b67caaa8c4fe66b6f59231ca56999b83e710\"" May 13 23:43:28.114202 containerd[1459]: time="2025-05-13T23:43:28.114165389Z" level=info msg="connecting to shim ec82ead72423dcc2468e8b3fc2b0b67caaa8c4fe66b6f59231ca56999b83e710" address="unix:///run/containerd/s/37fbfd536c75798a7a17b08621f2183b0124c57fdd4ddb346af381e70569ea21" protocol=ttrpc version=3 May 13 23:43:28.136814 systemd[1]: Started cri-containerd-ec82ead72423dcc2468e8b3fc2b0b67caaa8c4fe66b6f59231ca56999b83e710.scope - libcontainer container ec82ead72423dcc2468e8b3fc2b0b67caaa8c4fe66b6f59231ca56999b83e710. May 13 23:43:28.166781 containerd[1459]: time="2025-05-13T23:43:28.166734069Z" level=info msg="StartContainer for \"ec82ead72423dcc2468e8b3fc2b0b67caaa8c4fe66b6f59231ca56999b83e710\" returns successfully" May 13 23:43:28.661407 kubelet[1737]: E0513 23:43:28.661355 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:28.864590 kubelet[1737]: I0513 23:43:28.864499 1737 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.482979589 podStartE2EDuration="5.864484389s" podCreationTimestamp="2025-05-13 23:43:23 +0000 UTC" firstStartedPulling="2025-05-13 23:43:24.714621909 +0000 UTC m=+19.694907121" lastFinishedPulling="2025-05-13 23:43:28.096126709 +0000 UTC m=+23.076411921" observedRunningTime="2025-05-13 23:43:28.864352509 +0000 UTC m=+23.844637721" watchObservedRunningTime="2025-05-13 23:43:28.864484389 +0000 UTC m=+23.844769601" May 13 23:43:29.662938 kubelet[1737]: E0513 23:43:29.662475 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:30.663221 kubelet[1737]: E0513 23:43:30.663154 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:31.664093 kubelet[1737]: E0513 23:43:31.664024 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:32.664204 kubelet[1737]: E0513 23:43:32.664131 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:33.665118 kubelet[1737]: E0513 23:43:33.665051 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:34.665914 kubelet[1737]: E0513 23:43:34.665851 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:35.665963 kubelet[1737]: E0513 23:43:35.665926 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:36.667032 kubelet[1737]: E0513 23:43:36.666987 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:37.611775 systemd[1]: Created slice kubepods-besteffort-pod311898f1_c0c6_4bd3_8988_44a66bb99424.slice - libcontainer container kubepods-besteffort-pod311898f1_c0c6_4bd3_8988_44a66bb99424.slice. May 13 23:43:37.667651 kubelet[1737]: E0513 23:43:37.667615 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:37.671816 kubelet[1737]: I0513 23:43:37.671780 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-49ksr\" (UniqueName: \"kubernetes.io/projected/311898f1-c0c6-4bd3-8988-44a66bb99424-kube-api-access-49ksr\") pod \"test-pod-1\" (UID: \"311898f1-c0c6-4bd3-8988-44a66bb99424\") " pod="default/test-pod-1" May 13 23:43:37.671816 kubelet[1737]: I0513 23:43:37.671819 1737 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-91493cd7-c845-4578-a944-744bc8b99155\" (UniqueName: \"kubernetes.io/nfs/311898f1-c0c6-4bd3-8988-44a66bb99424-pvc-91493cd7-c845-4578-a944-744bc8b99155\") pod \"test-pod-1\" (UID: \"311898f1-c0c6-4bd3-8988-44a66bb99424\") " pod="default/test-pod-1" May 13 23:43:37.797630 kernel: FS-Cache: Loaded May 13 23:43:37.826211 kernel: RPC: Registered named UNIX socket transport module. May 13 23:43:37.826312 kernel: RPC: Registered udp transport module. May 13 23:43:37.826326 kernel: RPC: Registered tcp transport module. May 13 23:43:37.826870 kernel: RPC: Registered tcp-with-tls transport module. May 13 23:43:37.828179 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 13 23:43:38.007659 kernel: NFS: Registering the id_resolver key type May 13 23:43:38.007790 kernel: Key type id_resolver registered May 13 23:43:38.007811 kernel: Key type id_legacy registered May 13 23:43:38.029616 nfsidmap[2742]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 13 23:43:38.031748 nfsidmap[2743]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 13 23:43:38.215570 containerd[1459]: time="2025-05-13T23:43:38.215530033Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:311898f1-c0c6-4bd3-8988-44a66bb99424,Namespace:default,Attempt:0,}" May 13 23:43:38.229661 systemd-networkd[1385]: veth63eca4df: Link UP May 13 23:43:38.232331 kernel: cni0: port 3(veth63eca4df) entered blocking state May 13 23:43:38.232414 kernel: cni0: port 3(veth63eca4df) entered disabled state May 13 23:43:38.233082 kernel: veth63eca4df: entered allmulticast mode May 13 23:43:38.233937 kernel: veth63eca4df: entered promiscuous mode May 13 23:43:38.242238 kernel: cni0: port 3(veth63eca4df) entered blocking state May 13 23:43:38.242317 kernel: cni0: port 3(veth63eca4df) entered forwarding state May 13 23:43:38.242014 systemd-networkd[1385]: veth63eca4df: Gained carrier May 13 23:43:38.243409 containerd[1459]: 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"} May 13 23:43:38.243409 containerd[1459]: delegateAdd: netconf sent to delegate plugin: May 13 23:43:38.268930 containerd[1459]: {"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-05-13T23:43:38.268882795Z" level=info msg="connecting to shim 82498fa88e2be9639abb797de1123d70be557f3979d2578489214b1eef8ddcad" address="unix:///run/containerd/s/1e88a4e0bd2e7df7e0b478ff2df7f76771dd1a1970a1b0b32cd9e7f4e0a04aac" namespace=k8s.io protocol=ttrpc version=3 May 13 23:43:38.292803 systemd[1]: Started cri-containerd-82498fa88e2be9639abb797de1123d70be557f3979d2578489214b1eef8ddcad.scope - libcontainer container 82498fa88e2be9639abb797de1123d70be557f3979d2578489214b1eef8ddcad. May 13 23:43:38.305774 systemd-resolved[1325]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 13 23:43:38.325772 containerd[1459]: time="2025-05-13T23:43:38.325729632Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:311898f1-c0c6-4bd3-8988-44a66bb99424,Namespace:default,Attempt:0,} returns sandbox id \"82498fa88e2be9639abb797de1123d70be557f3979d2578489214b1eef8ddcad\"" May 13 23:43:38.326866 containerd[1459]: time="2025-05-13T23:43:38.326817536Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 13 23:43:38.576943 containerd[1459]: time="2025-05-13T23:43:38.576882979Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 13 23:43:38.577340 containerd[1459]: time="2025-05-13T23:43:38.577294508Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 13 23:43:38.580653 containerd[1459]: time="2025-05-13T23:43:38.580530698Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"69948737\" in 253.675642ms" May 13 23:43:38.580653 containerd[1459]: time="2025-05-13T23:43:38.580581219Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 13 23:43:38.584169 containerd[1459]: time="2025-05-13T23:43:38.582628224Z" level=info msg="CreateContainer within sandbox \"82498fa88e2be9639abb797de1123d70be557f3979d2578489214b1eef8ddcad\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 13 23:43:38.593322 containerd[1459]: time="2025-05-13T23:43:38.593259175Z" level=info msg="Container e8591b3ba543367091b89c601d1cdc61e3c67a83690071d5ec73e35c316ef1e8: CDI devices from CRI Config.CDIDevices: []" May 13 23:43:38.599891 containerd[1459]: time="2025-05-13T23:43:38.599726316Z" level=info msg="CreateContainer within sandbox \"82498fa88e2be9639abb797de1123d70be557f3979d2578489214b1eef8ddcad\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"e8591b3ba543367091b89c601d1cdc61e3c67a83690071d5ec73e35c316ef1e8\"" May 13 23:43:38.600634 containerd[1459]: time="2025-05-13T23:43:38.600523333Z" level=info msg="StartContainer for \"e8591b3ba543367091b89c601d1cdc61e3c67a83690071d5ec73e35c316ef1e8\"" May 13 23:43:38.601718 containerd[1459]: time="2025-05-13T23:43:38.601685319Z" level=info msg="connecting to shim e8591b3ba543367091b89c601d1cdc61e3c67a83690071d5ec73e35c316ef1e8" address="unix:///run/containerd/s/1e88a4e0bd2e7df7e0b478ff2df7f76771dd1a1970a1b0b32cd9e7f4e0a04aac" protocol=ttrpc version=3 May 13 23:43:38.626841 systemd[1]: Started cri-containerd-e8591b3ba543367091b89c601d1cdc61e3c67a83690071d5ec73e35c316ef1e8.scope - libcontainer container e8591b3ba543367091b89c601d1cdc61e3c67a83690071d5ec73e35c316ef1e8. May 13 23:43:38.654244 containerd[1459]: time="2025-05-13T23:43:38.654180261Z" level=info msg="StartContainer for \"e8591b3ba543367091b89c601d1cdc61e3c67a83690071d5ec73e35c316ef1e8\" returns successfully" May 13 23:43:38.667961 kubelet[1737]: E0513 23:43:38.667922 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:39.476865 systemd-networkd[1385]: veth63eca4df: Gained IPv6LL May 13 23:43:39.668137 kubelet[1737]: E0513 23:43:39.668093 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:40.669238 kubelet[1737]: E0513 23:43:40.669182 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 13 23:43:41.669942 kubelet[1737]: E0513 23:43:41.669896 1737 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"