May 14 18:13:44.847526 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 14 18:13:44.847546 kernel: Linux version 6.12.20-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 Wed May 14 16:42:23 -00 2025 May 14 18:13:44.847556 kernel: KASLR enabled May 14 18:13:44.847562 kernel: efi: EFI v2.7 by EDK II May 14 18:13:44.847568 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 May 14 18:13:44.847574 kernel: random: crng init done May 14 18:13:44.847580 kernel: secureboot: Secure boot disabled May 14 18:13:44.847586 kernel: ACPI: Early table checksum verification disabled May 14 18:13:44.847592 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) May 14 18:13:44.847599 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 14 18:13:44.847605 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847610 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847616 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847622 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847629 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847704 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847713 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847719 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847725 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 14 18:13:44.847731 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 14 18:13:44.847738 kernel: ACPI: Use ACPI SPCR as default console: Yes May 14 18:13:44.847744 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 14 18:13:44.847750 kernel: NODE_DATA(0) allocated [mem 0xdc965dc0-0xdc96cfff] May 14 18:13:44.847756 kernel: Zone ranges: May 14 18:13:44.847762 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 14 18:13:44.847770 kernel: DMA32 empty May 14 18:13:44.847776 kernel: Normal empty May 14 18:13:44.847783 kernel: Device empty May 14 18:13:44.847789 kernel: Movable zone start for each node May 14 18:13:44.847794 kernel: Early memory node ranges May 14 18:13:44.847801 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] May 14 18:13:44.847807 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] May 14 18:13:44.847813 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] May 14 18:13:44.847819 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] May 14 18:13:44.847825 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] May 14 18:13:44.847831 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] May 14 18:13:44.847836 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] May 14 18:13:44.847843 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] May 14 18:13:44.847849 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] May 14 18:13:44.847856 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 14 18:13:44.847864 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 14 18:13:44.847871 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 14 18:13:44.847877 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 14 18:13:44.847885 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 14 18:13:44.847892 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 14 18:13:44.847898 kernel: psci: probing for conduit method from ACPI. May 14 18:13:44.847904 kernel: psci: PSCIv1.1 detected in firmware. May 14 18:13:44.847911 kernel: psci: Using standard PSCI v0.2 function IDs May 14 18:13:44.847917 kernel: psci: Trusted OS migration not required May 14 18:13:44.847924 kernel: psci: SMC Calling Convention v1.1 May 14 18:13:44.847930 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 14 18:13:44.847937 kernel: percpu: Embedded 33 pages/cpu s98136 r8192 d28840 u135168 May 14 18:13:44.847943 kernel: pcpu-alloc: s98136 r8192 d28840 u135168 alloc=33*4096 May 14 18:13:44.847951 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 14 18:13:44.847958 kernel: Detected PIPT I-cache on CPU0 May 14 18:13:44.847964 kernel: CPU features: detected: GIC system register CPU interface May 14 18:13:44.847970 kernel: CPU features: detected: Spectre-v4 May 14 18:13:44.847977 kernel: CPU features: detected: Spectre-BHB May 14 18:13:44.847983 kernel: CPU features: kernel page table isolation forced ON by KASLR May 14 18:13:44.847989 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 14 18:13:44.847996 kernel: CPU features: detected: ARM erratum 1418040 May 14 18:13:44.848002 kernel: CPU features: detected: SSBS not fully self-synchronizing May 14 18:13:44.848008 kernel: alternatives: applying boot alternatives May 14 18:13:44.848015 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=fb5d39925446c9958629410eadbe2d2aa0566996d55f4385bdd8a5ce4ad5f562 May 14 18:13:44.848023 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 14 18:13:44.848030 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 14 18:13:44.848036 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 14 18:13:44.848042 kernel: Fallback order for Node 0: 0 May 14 18:13:44.848048 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 May 14 18:13:44.848055 kernel: Policy zone: DMA May 14 18:13:44.848061 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 14 18:13:44.848067 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB May 14 18:13:44.848074 kernel: software IO TLB: area num 4. May 14 18:13:44.848080 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB May 14 18:13:44.848086 kernel: software IO TLB: mapped [mem 0x00000000d8c00000-0x00000000d9000000] (4MB) May 14 18:13:44.848092 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 14 18:13:44.848100 kernel: rcu: Preemptible hierarchical RCU implementation. May 14 18:13:44.848107 kernel: rcu: RCU event tracing is enabled. May 14 18:13:44.848114 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 14 18:13:44.848120 kernel: Trampoline variant of Tasks RCU enabled. May 14 18:13:44.848127 kernel: Tracing variant of Tasks RCU enabled. May 14 18:13:44.848133 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 14 18:13:44.848139 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 14 18:13:44.848146 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 14 18:13:44.848153 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 14 18:13:44.848166 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 14 18:13:44.848173 kernel: GICv3: 256 SPIs implemented May 14 18:13:44.848181 kernel: GICv3: 0 Extended SPIs implemented May 14 18:13:44.848187 kernel: Root IRQ handler: gic_handle_irq May 14 18:13:44.848194 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 14 18:13:44.848200 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 May 14 18:13:44.848206 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 14 18:13:44.848212 kernel: ITS [mem 0x08080000-0x0809ffff] May 14 18:13:44.848219 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400e0000 (indirect, esz 8, psz 64K, shr 1) May 14 18:13:44.848225 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400f0000 (flat, esz 8, psz 64K, shr 1) May 14 18:13:44.848232 kernel: GICv3: using LPI property table @0x0000000040100000 May 14 18:13:44.848238 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040110000 May 14 18:13:44.848245 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 14 18:13:44.848251 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 18:13:44.848259 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 14 18:13:44.848265 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 14 18:13:44.848272 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 14 18:13:44.848279 kernel: arm-pv: using stolen time PV May 14 18:13:44.848285 kernel: Console: colour dummy device 80x25 May 14 18:13:44.848292 kernel: ACPI: Core revision 20240827 May 14 18:13:44.848299 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 14 18:13:44.848305 kernel: pid_max: default: 32768 minimum: 301 May 14 18:13:44.848312 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 14 18:13:44.848319 kernel: landlock: Up and running. May 14 18:13:44.848326 kernel: SELinux: Initializing. May 14 18:13:44.848332 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 14 18:13:44.848339 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 14 18:13:44.848345 kernel: rcu: Hierarchical SRCU implementation. May 14 18:13:44.848352 kernel: rcu: Max phase no-delay instances is 400. May 14 18:13:44.848359 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 14 18:13:44.848366 kernel: Remapping and enabling EFI services. May 14 18:13:44.848372 kernel: smp: Bringing up secondary CPUs ... May 14 18:13:44.848379 kernel: Detected PIPT I-cache on CPU1 May 14 18:13:44.848391 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 14 18:13:44.848398 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040120000 May 14 18:13:44.848406 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 18:13:44.848413 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 14 18:13:44.848420 kernel: Detected PIPT I-cache on CPU2 May 14 18:13:44.848427 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 14 18:13:44.848434 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040130000 May 14 18:13:44.848442 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 18:13:44.848449 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 14 18:13:44.848455 kernel: Detected PIPT I-cache on CPU3 May 14 18:13:44.848462 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 14 18:13:44.848469 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040140000 May 14 18:13:44.848476 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 18:13:44.848483 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 14 18:13:44.848490 kernel: smp: Brought up 1 node, 4 CPUs May 14 18:13:44.848497 kernel: SMP: Total of 4 processors activated. May 14 18:13:44.848503 kernel: CPU: All CPU(s) started at EL1 May 14 18:13:44.848511 kernel: CPU features: detected: 32-bit EL0 Support May 14 18:13:44.848518 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 14 18:13:44.848526 kernel: CPU features: detected: Common not Private translations May 14 18:13:44.848533 kernel: CPU features: detected: CRC32 instructions May 14 18:13:44.848540 kernel: CPU features: detected: Enhanced Virtualization Traps May 14 18:13:44.848547 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 14 18:13:44.848554 kernel: CPU features: detected: LSE atomic instructions May 14 18:13:44.848561 kernel: CPU features: detected: Privileged Access Never May 14 18:13:44.848568 kernel: CPU features: detected: RAS Extension Support May 14 18:13:44.848576 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 14 18:13:44.848583 kernel: alternatives: applying system-wide alternatives May 14 18:13:44.848590 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 May 14 18:13:44.848597 kernel: Memory: 2440984K/2572288K available (11072K kernel code, 2276K rwdata, 8928K rodata, 39424K init, 1034K bss, 125536K reserved, 0K cma-reserved) May 14 18:13:44.848604 kernel: devtmpfs: initialized May 14 18:13:44.848611 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 14 18:13:44.848618 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 14 18:13:44.848625 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 14 18:13:44.848632 kernel: 0 pages in range for non-PLT usage May 14 18:13:44.848657 kernel: 508544 pages in range for PLT usage May 14 18:13:44.848667 kernel: pinctrl core: initialized pinctrl subsystem May 14 18:13:44.848674 kernel: SMBIOS 3.0.0 present. May 14 18:13:44.848681 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 14 18:13:44.848688 kernel: DMI: Memory slots populated: 1/1 May 14 18:13:44.848695 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 14 18:13:44.848702 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 14 18:13:44.848709 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 14 18:13:44.848716 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 14 18:13:44.848725 kernel: audit: initializing netlink subsys (disabled) May 14 18:13:44.848732 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 May 14 18:13:44.848739 kernel: thermal_sys: Registered thermal governor 'step_wise' May 14 18:13:44.848746 kernel: cpuidle: using governor menu May 14 18:13:44.848752 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 14 18:13:44.848759 kernel: ASID allocator initialised with 32768 entries May 14 18:13:44.848766 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 14 18:13:44.848773 kernel: Serial: AMBA PL011 UART driver May 14 18:13:44.848780 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 14 18:13:44.848788 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 14 18:13:44.848795 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 14 18:13:44.848802 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 14 18:13:44.848808 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 14 18:13:44.848815 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 14 18:13:44.848822 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 14 18:13:44.848829 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 14 18:13:44.848835 kernel: ACPI: Added _OSI(Module Device) May 14 18:13:44.848842 kernel: ACPI: Added _OSI(Processor Device) May 14 18:13:44.848850 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 14 18:13:44.848857 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 14 18:13:44.848863 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 14 18:13:44.848870 kernel: ACPI: Interpreter enabled May 14 18:13:44.848877 kernel: ACPI: Using GIC for interrupt routing May 14 18:13:44.848884 kernel: ACPI: MCFG table detected, 1 entries May 14 18:13:44.848891 kernel: ACPI: CPU0 has been hot-added May 14 18:13:44.848897 kernel: ACPI: CPU1 has been hot-added May 14 18:13:44.848904 kernel: ACPI: CPU2 has been hot-added May 14 18:13:44.848911 kernel: ACPI: CPU3 has been hot-added May 14 18:13:44.848919 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 14 18:13:44.848926 kernel: printk: legacy console [ttyAMA0] enabled May 14 18:13:44.848933 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 14 18:13:44.849063 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 14 18:13:44.849126 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 14 18:13:44.849193 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 14 18:13:44.849253 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 14 18:13:44.849311 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 14 18:13:44.849319 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 14 18:13:44.849326 kernel: PCI host bridge to bus 0000:00 May 14 18:13:44.849391 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 14 18:13:44.849446 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 14 18:13:44.849500 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 14 18:13:44.849551 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 14 18:13:44.849629 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint May 14 18:13:44.849748 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint May 14 18:13:44.849810 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] May 14 18:13:44.849869 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] May 14 18:13:44.849926 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] May 14 18:13:44.849983 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned May 14 18:13:44.850043 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned May 14 18:13:44.850120 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned May 14 18:13:44.850183 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 14 18:13:44.850236 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 14 18:13:44.850287 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 14 18:13:44.850296 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 14 18:13:44.850303 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 14 18:13:44.850310 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 14 18:13:44.850319 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 14 18:13:44.850326 kernel: iommu: Default domain type: Translated May 14 18:13:44.850333 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 14 18:13:44.850339 kernel: efivars: Registered efivars operations May 14 18:13:44.850346 kernel: vgaarb: loaded May 14 18:13:44.850353 kernel: clocksource: Switched to clocksource arch_sys_counter May 14 18:13:44.850360 kernel: VFS: Disk quotas dquot_6.6.0 May 14 18:13:44.850367 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 14 18:13:44.850374 kernel: pnp: PnP ACPI init May 14 18:13:44.850440 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 14 18:13:44.850449 kernel: pnp: PnP ACPI: found 1 devices May 14 18:13:44.850456 kernel: NET: Registered PF_INET protocol family May 14 18:13:44.850463 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 14 18:13:44.850470 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 14 18:13:44.850477 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 14 18:13:44.850484 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 14 18:13:44.850491 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 14 18:13:44.850500 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 14 18:13:44.850507 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 14 18:13:44.850514 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 14 18:13:44.850521 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 14 18:13:44.850528 kernel: PCI: CLS 0 bytes, default 64 May 14 18:13:44.850534 kernel: kvm [1]: HYP mode not available May 14 18:13:44.850542 kernel: Initialise system trusted keyrings May 14 18:13:44.850548 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 14 18:13:44.850555 kernel: Key type asymmetric registered May 14 18:13:44.850562 kernel: Asymmetric key parser 'x509' registered May 14 18:13:44.850570 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 14 18:13:44.850577 kernel: io scheduler mq-deadline registered May 14 18:13:44.850584 kernel: io scheduler kyber registered May 14 18:13:44.850591 kernel: io scheduler bfq registered May 14 18:13:44.850599 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 14 18:13:44.850605 kernel: ACPI: button: Power Button [PWRB] May 14 18:13:44.850613 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 14 18:13:44.850687 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 14 18:13:44.850698 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 14 18:13:44.850708 kernel: thunder_xcv, ver 1.0 May 14 18:13:44.850715 kernel: thunder_bgx, ver 1.0 May 14 18:13:44.850722 kernel: nicpf, ver 1.0 May 14 18:13:44.850733 kernel: nicvf, ver 1.0 May 14 18:13:44.850808 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 14 18:13:44.850876 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-14T18:13:44 UTC (1747246424) May 14 18:13:44.850886 kernel: hid: raw HID events driver (C) Jiri Kosina May 14 18:13:44.850895 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available May 14 18:13:44.850904 kernel: watchdog: NMI not fully supported May 14 18:13:44.850912 kernel: watchdog: Hard watchdog permanently disabled May 14 18:13:44.850919 kernel: NET: Registered PF_INET6 protocol family May 14 18:13:44.850927 kernel: Segment Routing with IPv6 May 14 18:13:44.850935 kernel: In-situ OAM (IOAM) with IPv6 May 14 18:13:44.850942 kernel: NET: Registered PF_PACKET protocol family May 14 18:13:44.850949 kernel: Key type dns_resolver registered May 14 18:13:44.850961 kernel: registered taskstats version 1 May 14 18:13:44.850968 kernel: Loading compiled-in X.509 certificates May 14 18:13:44.850976 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.20-flatcar: c0c250ba312a1bb9bceb2432c486db6e5999df1a' May 14 18:13:44.850983 kernel: Demotion targets for Node 0: null May 14 18:13:44.850990 kernel: Key type .fscrypt registered May 14 18:13:44.850997 kernel: Key type fscrypt-provisioning registered May 14 18:13:44.851004 kernel: ima: No TPM chip found, activating TPM-bypass! May 14 18:13:44.851010 kernel: ima: Allocated hash algorithm: sha1 May 14 18:13:44.851017 kernel: ima: No architecture policies found May 14 18:13:44.851024 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 14 18:13:44.851032 kernel: clk: Disabling unused clocks May 14 18:13:44.851039 kernel: PM: genpd: Disabling unused power domains May 14 18:13:44.851046 kernel: Warning: unable to open an initial console. May 14 18:13:44.851053 kernel: Freeing unused kernel memory: 39424K May 14 18:13:44.851060 kernel: Run /init as init process May 14 18:13:44.851067 kernel: with arguments: May 14 18:13:44.851073 kernel: /init May 14 18:13:44.851080 kernel: with environment: May 14 18:13:44.851087 kernel: HOME=/ May 14 18:13:44.851094 kernel: TERM=linux May 14 18:13:44.851102 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 14 18:13:44.851109 systemd[1]: Successfully made /usr/ read-only. May 14 18:13:44.851119 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 14 18:13:44.851127 systemd[1]: Detected virtualization kvm. May 14 18:13:44.851134 systemd[1]: Detected architecture arm64. May 14 18:13:44.851141 systemd[1]: Running in initrd. May 14 18:13:44.851148 systemd[1]: No hostname configured, using default hostname. May 14 18:13:44.851163 systemd[1]: Hostname set to . May 14 18:13:44.851170 systemd[1]: Initializing machine ID from VM UUID. May 14 18:13:44.851177 systemd[1]: Queued start job for default target initrd.target. May 14 18:13:44.851185 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 18:13:44.851192 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 18:13:44.851200 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 14 18:13:44.851208 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 18:13:44.851215 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 14 18:13:44.851226 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 14 18:13:44.851234 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 14 18:13:44.851242 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 14 18:13:44.851250 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 18:13:44.851257 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 18:13:44.851265 systemd[1]: Reached target paths.target - Path Units. May 14 18:13:44.851272 systemd[1]: Reached target slices.target - Slice Units. May 14 18:13:44.851281 systemd[1]: Reached target swap.target - Swaps. May 14 18:13:44.851289 systemd[1]: Reached target timers.target - Timer Units. May 14 18:13:44.851296 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 14 18:13:44.851304 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 18:13:44.851312 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 14 18:13:44.851319 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 14 18:13:44.851327 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 18:13:44.851335 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 18:13:44.851343 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 18:13:44.851351 systemd[1]: Reached target sockets.target - Socket Units. May 14 18:13:44.851358 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 14 18:13:44.851366 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 18:13:44.851373 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 14 18:13:44.851381 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 14 18:13:44.851389 systemd[1]: Starting systemd-fsck-usr.service... May 14 18:13:44.851396 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 18:13:44.851404 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 18:13:44.851413 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:13:44.851420 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 14 18:13:44.851428 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 18:13:44.851436 systemd[1]: Finished systemd-fsck-usr.service. May 14 18:13:44.851445 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 14 18:13:44.851469 systemd-journald[245]: Collecting audit messages is disabled. May 14 18:13:44.851488 systemd-journald[245]: Journal started May 14 18:13:44.851508 systemd-journald[245]: Runtime Journal (/run/log/journal/b9665a79b8e54137a663a45ccc7e049f) is 6M, max 48.5M, 42.4M free. May 14 18:13:44.840559 systemd-modules-load[247]: Inserted module 'overlay' May 14 18:13:44.857446 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:13:44.857466 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 14 18:13:44.860703 systemd[1]: Started systemd-journald.service - Journal Service. May 14 18:13:44.862747 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 14 18:13:44.867013 kernel: Bridge firewalling registered May 14 18:13:44.865171 systemd-modules-load[247]: Inserted module 'br_netfilter' May 14 18:13:44.865993 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 18:13:44.869396 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 18:13:44.871229 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 18:13:44.873809 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 18:13:44.880209 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 18:13:44.888206 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 18:13:44.890196 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 18:13:44.891024 systemd-tmpfiles[269]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 14 18:13:44.893825 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 18:13:44.896450 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 18:13:44.898961 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 18:13:44.901358 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 14 18:13:44.920185 dracut-cmdline[287]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=fb5d39925446c9958629410eadbe2d2aa0566996d55f4385bdd8a5ce4ad5f562 May 14 18:13:44.938744 systemd-resolved[284]: Positive Trust Anchors: May 14 18:13:44.938762 systemd-resolved[284]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 18:13:44.938794 systemd-resolved[284]: 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 14 18:13:44.947826 systemd-resolved[284]: Defaulting to hostname 'linux'. May 14 18:13:44.949049 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 18:13:44.950136 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 18:13:45.004673 kernel: SCSI subsystem initialized May 14 18:13:45.009657 kernel: Loading iSCSI transport class v2.0-870. May 14 18:13:45.017668 kernel: iscsi: registered transport (tcp) May 14 18:13:45.032673 kernel: iscsi: registered transport (qla4xxx) May 14 18:13:45.032730 kernel: QLogic iSCSI HBA Driver May 14 18:13:45.052098 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 18:13:45.073170 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 18:13:45.074930 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 18:13:45.123336 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 14 18:13:45.125655 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 14 18:13:45.187679 kernel: raid6: neonx8 gen() 15771 MB/s May 14 18:13:45.204658 kernel: raid6: neonx4 gen() 15807 MB/s May 14 18:13:45.221664 kernel: raid6: neonx2 gen() 13239 MB/s May 14 18:13:45.238656 kernel: raid6: neonx1 gen() 10412 MB/s May 14 18:13:45.255657 kernel: raid6: int64x8 gen() 6895 MB/s May 14 18:13:45.272653 kernel: raid6: int64x4 gen() 7334 MB/s May 14 18:13:45.289664 kernel: raid6: int64x2 gen() 6095 MB/s May 14 18:13:45.306860 kernel: raid6: int64x1 gen() 5043 MB/s May 14 18:13:45.306918 kernel: raid6: using algorithm neonx4 gen() 15807 MB/s May 14 18:13:45.324781 kernel: raid6: .... xor() 12369 MB/s, rmw enabled May 14 18:13:45.324818 kernel: raid6: using neon recovery algorithm May 14 18:13:45.330670 kernel: xor: measuring software checksum speed May 14 18:13:45.330698 kernel: 8regs : 21613 MB/sec May 14 18:13:45.330708 kernel: 32regs : 18565 MB/sec May 14 18:13:45.331830 kernel: arm64_neon : 27993 MB/sec May 14 18:13:45.331841 kernel: xor: using function: arm64_neon (27993 MB/sec) May 14 18:13:45.389884 kernel: Btrfs loaded, zoned=no, fsverity=no May 14 18:13:45.396508 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 14 18:13:45.399476 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 18:13:45.426191 systemd-udevd[498]: Using default interface naming scheme 'v255'. May 14 18:13:45.430409 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 18:13:45.432830 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 14 18:13:45.463485 dracut-pre-trigger[506]: rd.md=0: removing MD RAID activation May 14 18:13:45.494095 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 14 18:13:45.496237 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 18:13:45.545665 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 18:13:45.549524 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 14 18:13:45.597707 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 14 18:13:45.606288 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 14 18:13:45.606390 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 14 18:13:45.606401 kernel: GPT:9289727 != 19775487 May 14 18:13:45.606409 kernel: GPT:Alternate GPT header not at the end of the disk. May 14 18:13:45.606418 kernel: GPT:9289727 != 19775487 May 14 18:13:45.606426 kernel: GPT: Use GNU Parted to correct GPT errors. May 14 18:13:45.606434 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 14 18:13:45.604999 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 18:13:45.605120 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:13:45.609288 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:13:45.611280 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:13:45.641584 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 14 18:13:45.644345 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:13:45.645786 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 14 18:13:45.665126 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 14 18:13:45.672511 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 14 18:13:45.678725 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 14 18:13:45.679921 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 14 18:13:45.683104 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 14 18:13:45.685518 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 18:13:45.688702 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 18:13:45.691438 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 14 18:13:45.693567 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 14 18:13:45.710585 disk-uuid[589]: Primary Header is updated. May 14 18:13:45.710585 disk-uuid[589]: Secondary Entries is updated. May 14 18:13:45.710585 disk-uuid[589]: Secondary Header is updated. May 14 18:13:45.714669 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 14 18:13:45.717680 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 14 18:13:46.726511 disk-uuid[593]: The operation has completed successfully. May 14 18:13:46.728102 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 14 18:13:46.763380 systemd[1]: disk-uuid.service: Deactivated successfully. May 14 18:13:46.763478 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 14 18:13:46.792070 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 14 18:13:46.810713 sh[609]: Success May 14 18:13:46.828994 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 14 18:13:46.829034 kernel: device-mapper: uevent: version 1.0.3 May 14 18:13:46.832005 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 14 18:13:46.839686 kernel: device-mapper: verity: sha256 using shash "sha256-ce" May 14 18:13:46.878245 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 14 18:13:46.881072 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 14 18:13:46.899251 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 14 18:13:46.917975 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 14 18:13:46.918034 kernel: BTRFS: device fsid e21bbf34-4c71-4257-bd6f-908a2b81e5ab devid 1 transid 41 /dev/mapper/usr (253:0) scanned by mount (621) May 14 18:13:46.919683 kernel: BTRFS info (device dm-0): first mount of filesystem e21bbf34-4c71-4257-bd6f-908a2b81e5ab May 14 18:13:46.919717 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 14 18:13:46.921282 kernel: BTRFS info (device dm-0): using free-space-tree May 14 18:13:46.927782 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 14 18:13:46.929066 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 14 18:13:46.930509 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 14 18:13:46.931435 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 14 18:13:46.934249 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 14 18:13:46.957684 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (652) May 14 18:13:46.960068 kernel: BTRFS info (device vda6): first mount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 18:13:46.960106 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 14 18:13:46.960829 kernel: BTRFS info (device vda6): using free-space-tree May 14 18:13:46.977278 kernel: BTRFS info (device vda6): last unmount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 18:13:46.979748 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 14 18:13:46.981700 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 14 18:13:47.051683 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 18:13:47.060839 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 18:13:47.122420 systemd-networkd[798]: lo: Link UP May 14 18:13:47.122433 systemd-networkd[798]: lo: Gained carrier May 14 18:13:47.123508 systemd-networkd[798]: Enumeration completed May 14 18:13:47.123659 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 18:13:47.124654 systemd-networkd[798]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:13:47.124658 systemd-networkd[798]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 18:13:47.125736 systemd-networkd[798]: eth0: Link UP May 14 18:13:47.125740 systemd-networkd[798]: eth0: Gained carrier May 14 18:13:47.125754 systemd-networkd[798]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:13:47.126242 systemd[1]: Reached target network.target - Network. May 14 18:13:47.149724 systemd-networkd[798]: eth0: DHCPv4 address 10.0.0.116/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 14 18:13:47.187438 ignition[713]: Ignition 2.21.0 May 14 18:13:47.187450 ignition[713]: Stage: fetch-offline May 14 18:13:47.187481 ignition[713]: no configs at "/usr/lib/ignition/base.d" May 14 18:13:47.187489 ignition[713]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 18:13:47.187700 ignition[713]: parsed url from cmdline: "" May 14 18:13:47.187704 ignition[713]: no config URL provided May 14 18:13:47.187708 ignition[713]: reading system config file "/usr/lib/ignition/user.ign" May 14 18:13:47.187715 ignition[713]: no config at "/usr/lib/ignition/user.ign" May 14 18:13:47.187736 ignition[713]: op(1): [started] loading QEMU firmware config module May 14 18:13:47.187740 ignition[713]: op(1): executing: "modprobe" "qemu_fw_cfg" May 14 18:13:47.209586 ignition[713]: op(1): [finished] loading QEMU firmware config module May 14 18:13:47.217166 ignition[713]: parsing config with SHA512: d3a6a09b3a43032232e3445c5336fb33ec29b318d3e0f2b28247094c2b8c6e52c81ee902388a61e649c3af41c5d76bdec1b2b3cc4b8bd09d904e6ac70d213eee May 14 18:13:47.221549 unknown[713]: fetched base config from "system" May 14 18:13:47.221560 unknown[713]: fetched user config from "qemu" May 14 18:13:47.221824 ignition[713]: fetch-offline: fetch-offline passed May 14 18:13:47.224437 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 14 18:13:47.221876 ignition[713]: Ignition finished successfully May 14 18:13:47.226014 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 14 18:13:47.226833 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 14 18:13:47.259994 ignition[813]: Ignition 2.21.0 May 14 18:13:47.260010 ignition[813]: Stage: kargs May 14 18:13:47.260139 ignition[813]: no configs at "/usr/lib/ignition/base.d" May 14 18:13:47.260161 ignition[813]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 18:13:47.262599 ignition[813]: kargs: kargs passed May 14 18:13:47.262675 ignition[813]: Ignition finished successfully May 14 18:13:47.265222 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 14 18:13:47.269492 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 14 18:13:47.303219 ignition[821]: Ignition 2.21.0 May 14 18:13:47.303235 ignition[821]: Stage: disks May 14 18:13:47.303367 ignition[821]: no configs at "/usr/lib/ignition/base.d" May 14 18:13:47.303376 ignition[821]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 18:13:47.305692 ignition[821]: disks: disks passed May 14 18:13:47.306049 ignition[821]: Ignition finished successfully May 14 18:13:47.308332 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 14 18:13:47.310840 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 14 18:13:47.313469 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 14 18:13:47.314762 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 18:13:47.316762 systemd[1]: Reached target sysinit.target - System Initialization. May 14 18:13:47.318602 systemd[1]: Reached target basic.target - Basic System. May 14 18:13:47.321313 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 14 18:13:47.356734 systemd-fsck[831]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 14 18:13:47.361109 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 14 18:13:47.363884 systemd[1]: Mounting sysroot.mount - /sysroot... May 14 18:13:47.443609 systemd[1]: Mounted sysroot.mount - /sysroot. May 14 18:13:47.445031 kernel: EXT4-fs (vda9): mounted filesystem a9c1ea72-ce96-48c1-8c16-d7102e51beed r/w with ordered data mode. Quota mode: none. May 14 18:13:47.444812 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 14 18:13:47.447917 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 18:13:47.449711 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 14 18:13:47.450664 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 14 18:13:47.450719 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 14 18:13:47.450743 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 14 18:13:47.469202 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 14 18:13:47.471503 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 14 18:13:47.476622 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (839) May 14 18:13:47.476655 kernel: BTRFS info (device vda6): first mount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 18:13:47.476667 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 14 18:13:47.478651 kernel: BTRFS info (device vda6): using free-space-tree May 14 18:13:47.481585 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 18:13:47.531109 initrd-setup-root[864]: cut: /sysroot/etc/passwd: No such file or directory May 14 18:13:47.534267 initrd-setup-root[871]: cut: /sysroot/etc/group: No such file or directory May 14 18:13:47.537388 initrd-setup-root[878]: cut: /sysroot/etc/shadow: No such file or directory May 14 18:13:47.540240 initrd-setup-root[885]: cut: /sysroot/etc/gshadow: No such file or directory May 14 18:13:47.618238 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 14 18:13:47.620196 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 14 18:13:47.621712 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 14 18:13:47.644676 kernel: BTRFS info (device vda6): last unmount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 18:13:47.671753 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 14 18:13:47.683552 ignition[953]: INFO : Ignition 2.21.0 May 14 18:13:47.683552 ignition[953]: INFO : Stage: mount May 14 18:13:47.685323 ignition[953]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 18:13:47.685323 ignition[953]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 18:13:47.685323 ignition[953]: INFO : mount: mount passed May 14 18:13:47.685323 ignition[953]: INFO : Ignition finished successfully May 14 18:13:47.688703 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 14 18:13:47.690741 systemd[1]: Starting ignition-files.service - Ignition (files)... May 14 18:13:47.916191 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 14 18:13:47.917564 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 18:13:47.937661 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (966) May 14 18:13:47.939930 kernel: BTRFS info (device vda6): first mount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 18:13:47.939958 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 14 18:13:47.939968 kernel: BTRFS info (device vda6): using free-space-tree May 14 18:13:47.951106 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 18:13:47.995884 ignition[983]: INFO : Ignition 2.21.0 May 14 18:13:47.995884 ignition[983]: INFO : Stage: files May 14 18:13:47.997506 ignition[983]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 18:13:47.997506 ignition[983]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 18:13:47.997506 ignition[983]: DEBUG : files: compiled without relabeling support, skipping May 14 18:13:48.001019 ignition[983]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 14 18:13:48.001019 ignition[983]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 14 18:13:48.001019 ignition[983]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 14 18:13:48.001019 ignition[983]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 14 18:13:48.001019 ignition[983]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 14 18:13:48.000549 unknown[983]: wrote ssh authorized keys file for user: core May 14 18:13:48.009326 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 14 18:13:48.009326 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 14 18:13:48.009326 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 14 18:13:48.009326 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 14 18:13:48.009326 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 14 18:13:48.018459 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 14 18:13:48.018459 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 14 18:13:48.018459 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-arm64.raw: attempt #1 May 14 18:13:48.460269 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 14 18:13:48.758212 ignition[983]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 14 18:13:48.758212 ignition[983]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 14 18:13:48.761904 ignition[983]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 14 18:13:48.765992 ignition[983]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 14 18:13:48.765992 ignition[983]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 14 18:13:48.765992 ignition[983]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 14 18:13:48.783455 ignition[983]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 14 18:13:48.787444 ignition[983]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 14 18:13:48.790279 ignition[983]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 14 18:13:48.790279 ignition[983]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 14 18:13:48.790279 ignition[983]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 14 18:13:48.790279 ignition[983]: INFO : files: files passed May 14 18:13:48.790279 ignition[983]: INFO : Ignition finished successfully May 14 18:13:48.790950 systemd[1]: Finished ignition-files.service - Ignition (files). May 14 18:13:48.793816 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 14 18:13:48.796783 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 14 18:13:48.809563 systemd[1]: ignition-quench.service: Deactivated successfully. May 14 18:13:48.812242 initrd-setup-root-after-ignition[1011]: grep: /sysroot/oem/oem-release: No such file or directory May 14 18:13:48.810213 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 14 18:13:48.815806 initrd-setup-root-after-ignition[1014]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 18:13:48.815806 initrd-setup-root-after-ignition[1014]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 14 18:13:48.818820 initrd-setup-root-after-ignition[1018]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 18:13:48.820355 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 18:13:48.822923 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 14 18:13:48.824883 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 14 18:13:48.865573 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 14 18:13:48.865718 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 14 18:13:48.867793 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 14 18:13:48.869562 systemd[1]: Reached target initrd.target - Initrd Default Target. May 14 18:13:48.871295 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 14 18:13:48.872082 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 14 18:13:48.887711 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 18:13:48.889965 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 14 18:13:48.909405 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 14 18:13:48.910604 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 18:13:48.912528 systemd[1]: Stopped target timers.target - Timer Units. May 14 18:13:48.914230 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 14 18:13:48.914353 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 18:13:48.916656 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 14 18:13:48.917613 systemd[1]: Stopped target basic.target - Basic System. May 14 18:13:48.919359 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 14 18:13:48.921155 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 14 18:13:48.922890 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 14 18:13:48.924719 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 14 18:13:48.926692 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 14 18:13:48.928521 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 14 18:13:48.930506 systemd[1]: Stopped target sysinit.target - System Initialization. May 14 18:13:48.932281 systemd[1]: Stopped target local-fs.target - Local File Systems. May 14 18:13:48.934038 systemd[1]: Stopped target swap.target - Swaps. May 14 18:13:48.935561 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 14 18:13:48.935700 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 14 18:13:48.937956 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 14 18:13:48.939740 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 18:13:48.941587 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 14 18:13:48.941683 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 18:13:48.943582 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 14 18:13:48.943713 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 14 18:13:48.946304 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 14 18:13:48.946419 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 14 18:13:48.948734 systemd[1]: Stopped target paths.target - Path Units. May 14 18:13:48.950281 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 14 18:13:48.953669 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 18:13:48.955131 systemd[1]: Stopped target slices.target - Slice Units. May 14 18:13:48.956807 systemd[1]: Stopped target sockets.target - Socket Units. May 14 18:13:48.959017 systemd[1]: iscsid.socket: Deactivated successfully. May 14 18:13:48.959155 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 14 18:13:48.960593 systemd[1]: iscsiuio.socket: Deactivated successfully. May 14 18:13:48.960690 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 18:13:48.962241 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 14 18:13:48.962362 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 18:13:48.963988 systemd[1]: ignition-files.service: Deactivated successfully. May 14 18:13:48.964091 systemd[1]: Stopped ignition-files.service - Ignition (files). May 14 18:13:48.966474 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 14 18:13:48.968013 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 14 18:13:48.968137 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 14 18:13:48.991185 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 14 18:13:48.992025 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 14 18:13:48.992148 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 14 18:13:48.993958 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 14 18:13:48.994074 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 14 18:13:48.999717 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 14 18:13:49.000867 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 14 18:13:49.006022 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 14 18:13:49.009822 ignition[1038]: INFO : Ignition 2.21.0 May 14 18:13:49.009822 ignition[1038]: INFO : Stage: umount May 14 18:13:49.009822 ignition[1038]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 18:13:49.009822 ignition[1038]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 18:13:49.009822 ignition[1038]: INFO : umount: umount passed May 14 18:13:49.009822 ignition[1038]: INFO : Ignition finished successfully May 14 18:13:49.010988 systemd[1]: ignition-mount.service: Deactivated successfully. May 14 18:13:49.011711 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 14 18:13:49.013579 systemd[1]: Stopped target network.target - Network. May 14 18:13:49.015245 systemd[1]: ignition-disks.service: Deactivated successfully. May 14 18:13:49.015312 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 14 18:13:49.017762 systemd[1]: ignition-kargs.service: Deactivated successfully. May 14 18:13:49.017817 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 14 18:13:49.019482 systemd[1]: ignition-setup.service: Deactivated successfully. May 14 18:13:49.019532 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 14 18:13:49.021245 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 14 18:13:49.021287 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 14 18:13:49.022944 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 14 18:13:49.024596 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 14 18:13:49.033406 systemd[1]: systemd-resolved.service: Deactivated successfully. May 14 18:13:49.033543 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 14 18:13:49.036677 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 14 18:13:49.036879 systemd[1]: systemd-networkd.service: Deactivated successfully. May 14 18:13:49.036985 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 14 18:13:49.040421 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 14 18:13:49.040980 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 14 18:13:49.042800 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 14 18:13:49.042849 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 14 18:13:49.046632 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 14 18:13:49.047505 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 14 18:13:49.047567 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 18:13:49.049489 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 14 18:13:49.049541 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 14 18:13:49.052126 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 14 18:13:49.052183 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 14 18:13:49.054083 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 14 18:13:49.054131 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 18:13:49.057865 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 18:13:49.063199 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 14 18:13:49.063268 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 14 18:13:49.063563 systemd[1]: sysroot-boot.service: Deactivated successfully. May 14 18:13:49.063666 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 14 18:13:49.066629 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 14 18:13:49.066750 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 14 18:13:49.074435 systemd[1]: systemd-udevd.service: Deactivated successfully. May 14 18:13:49.074816 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 18:13:49.076748 systemd[1]: network-cleanup.service: Deactivated successfully. May 14 18:13:49.076835 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 14 18:13:49.078052 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 14 18:13:49.078090 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 14 18:13:49.079562 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 14 18:13:49.079591 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 14 18:13:49.081572 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 14 18:13:49.081622 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 14 18:13:49.084123 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 14 18:13:49.084258 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 14 18:13:49.086742 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 14 18:13:49.086793 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 18:13:49.090321 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 14 18:13:49.091359 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 14 18:13:49.091421 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 14 18:13:49.094196 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 14 18:13:49.094238 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 18:13:49.097020 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 18:13:49.097063 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:13:49.101286 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. May 14 18:13:49.101336 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 14 18:13:49.101368 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 18:13:49.104659 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 14 18:13:49.106665 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 14 18:13:49.107957 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 14 18:13:49.110654 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 14 18:13:49.126465 systemd[1]: Switching root. May 14 18:13:49.149868 systemd-journald[245]: Journal stopped May 14 18:13:49.912825 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). May 14 18:13:49.912881 kernel: SELinux: policy capability network_peer_controls=1 May 14 18:13:49.912898 kernel: SELinux: policy capability open_perms=1 May 14 18:13:49.912917 kernel: SELinux: policy capability extended_socket_class=1 May 14 18:13:49.912926 kernel: SELinux: policy capability always_check_network=0 May 14 18:13:49.912939 kernel: SELinux: policy capability cgroup_seclabel=1 May 14 18:13:49.912950 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 14 18:13:49.912963 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 14 18:13:49.912972 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 14 18:13:49.912981 kernel: SELinux: policy capability userspace_initial_context=0 May 14 18:13:49.912991 systemd[1]: Successfully loaded SELinux policy in 48.647ms. May 14 18:13:49.913007 kernel: audit: type=1403 audit(1747246429.305:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 14 18:13:49.913019 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.380ms. May 14 18:13:49.913035 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 14 18:13:49.913047 systemd[1]: Detected virtualization kvm. May 14 18:13:49.913058 systemd[1]: Detected architecture arm64. May 14 18:13:49.913068 systemd[1]: Detected first boot. May 14 18:13:49.913079 systemd[1]: Initializing machine ID from VM UUID. May 14 18:13:49.913090 zram_generator::config[1084]: No configuration found. May 14 18:13:49.913101 kernel: NET: Registered PF_VSOCK protocol family May 14 18:13:49.913111 systemd[1]: Populated /etc with preset unit settings. May 14 18:13:49.913121 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 14 18:13:49.913133 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 14 18:13:49.913150 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 14 18:13:49.913162 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 14 18:13:49.913172 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 14 18:13:49.913182 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 14 18:13:49.913192 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 14 18:13:49.913202 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 14 18:13:49.913212 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 14 18:13:49.913222 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 14 18:13:49.913234 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 14 18:13:49.913245 systemd[1]: Created slice user.slice - User and Session Slice. May 14 18:13:49.913255 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 18:13:49.913266 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 18:13:49.913276 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 14 18:13:49.913287 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 14 18:13:49.913298 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 14 18:13:49.913309 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 18:13:49.913320 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 14 18:13:49.913346 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 18:13:49.913358 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 18:13:49.913368 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 14 18:13:49.913378 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 14 18:13:49.913388 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 14 18:13:49.913398 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 14 18:13:49.913409 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 18:13:49.913420 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 18:13:49.913431 systemd[1]: Reached target slices.target - Slice Units. May 14 18:13:49.913442 systemd[1]: Reached target swap.target - Swaps. May 14 18:13:49.913452 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 14 18:13:49.913463 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 14 18:13:49.913473 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 14 18:13:49.913483 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 18:13:49.913493 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 18:13:49.913503 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 18:13:49.913513 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 14 18:13:49.913528 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 14 18:13:49.913539 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 14 18:13:49.913550 systemd[1]: Mounting media.mount - External Media Directory... May 14 18:13:49.913560 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 14 18:13:49.913571 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 14 18:13:49.913581 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 14 18:13:49.913596 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 14 18:13:49.913606 systemd[1]: Reached target machines.target - Containers. May 14 18:13:49.913618 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 14 18:13:49.913628 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 18:13:49.913647 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 18:13:49.913661 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 14 18:13:49.913671 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 18:13:49.913681 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 18:13:49.913702 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 18:13:49.913713 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 14 18:13:49.913726 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 18:13:49.913737 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 14 18:13:49.913748 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 14 18:13:49.913759 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 14 18:13:49.913770 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 14 18:13:49.913779 kernel: fuse: init (API version 7.41) May 14 18:13:49.913789 systemd[1]: Stopped systemd-fsck-usr.service. May 14 18:13:49.913799 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 14 18:13:49.913809 kernel: loop: module loaded May 14 18:13:49.913820 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 18:13:49.913830 kernel: ACPI: bus type drm_connector registered May 14 18:13:49.913840 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 18:13:49.913851 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 18:13:49.913861 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 14 18:13:49.913871 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 14 18:13:49.913881 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 18:13:49.913893 systemd[1]: verity-setup.service: Deactivated successfully. May 14 18:13:49.913903 systemd[1]: Stopped verity-setup.service. May 14 18:13:49.913935 systemd-journald[1159]: Collecting audit messages is disabled. May 14 18:13:49.913957 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 14 18:13:49.913969 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 14 18:13:49.913982 systemd-journald[1159]: Journal started May 14 18:13:49.914002 systemd-journald[1159]: Runtime Journal (/run/log/journal/b9665a79b8e54137a663a45ccc7e049f) is 6M, max 48.5M, 42.4M free. May 14 18:13:49.677321 systemd[1]: Queued start job for default target multi-user.target. May 14 18:13:49.699694 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 14 18:13:49.700082 systemd[1]: systemd-journald.service: Deactivated successfully. May 14 18:13:49.917958 systemd[1]: Started systemd-journald.service - Journal Service. May 14 18:13:49.918590 systemd[1]: Mounted media.mount - External Media Directory. May 14 18:13:49.919793 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 14 18:13:49.920984 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 14 18:13:49.922156 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 14 18:13:49.924380 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 14 18:13:49.927683 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 18:13:49.929218 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 14 18:13:49.929382 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 14 18:13:49.930848 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 18:13:49.931011 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 18:13:49.932490 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 18:13:49.932682 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 18:13:49.934042 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 18:13:49.934213 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 18:13:49.935842 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 14 18:13:49.936003 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 14 18:13:49.937311 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 18:13:49.937481 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 18:13:49.938930 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 18:13:49.940458 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 18:13:49.942104 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 14 18:13:49.943685 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 14 18:13:49.956124 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 18:13:49.958665 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 14 18:13:49.960682 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 14 18:13:49.961826 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 14 18:13:49.961864 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 18:13:49.963763 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 14 18:13:49.968765 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 14 18:13:49.970834 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 18:13:49.972130 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 14 18:13:49.974159 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 14 18:13:49.975423 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 18:13:49.976421 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 14 18:13:49.977546 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 18:13:49.978791 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 18:13:49.980867 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 14 18:13:49.984222 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 14 18:13:49.987870 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 18:13:49.989591 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 14 18:13:49.993889 systemd-journald[1159]: Time spent on flushing to /var/log/journal/b9665a79b8e54137a663a45ccc7e049f is 15.412ms for 870 entries. May 14 18:13:49.993889 systemd-journald[1159]: System Journal (/var/log/journal/b9665a79b8e54137a663a45ccc7e049f) is 8M, max 195.6M, 187.6M free. May 14 18:13:50.020499 systemd-journald[1159]: Received client request to flush runtime journal. May 14 18:13:50.020539 kernel: loop0: detected capacity change from 0 to 189592 May 14 18:13:50.020551 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 14 18:13:49.993021 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 14 18:13:49.995490 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 14 18:13:49.998397 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 14 18:13:50.004865 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 14 18:13:50.022237 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 14 18:13:50.030961 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 18:13:50.034435 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 14 18:13:50.036101 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 14 18:13:50.038761 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 14 18:13:50.042612 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 18:13:50.046664 kernel: loop1: detected capacity change from 0 to 107312 May 14 18:13:50.072816 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. May 14 18:13:50.073178 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. May 14 18:13:50.073723 kernel: loop2: detected capacity change from 0 to 138376 May 14 18:13:50.079742 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 18:13:50.103663 kernel: loop3: detected capacity change from 0 to 189592 May 14 18:13:50.109669 kernel: loop4: detected capacity change from 0 to 107312 May 14 18:13:50.114665 kernel: loop5: detected capacity change from 0 to 138376 May 14 18:13:50.120572 (sd-merge)[1225]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 14 18:13:50.120973 (sd-merge)[1225]: Merged extensions into '/usr'. May 14 18:13:50.124994 systemd[1]: Reload requested from client PID 1200 ('systemd-sysext') (unit systemd-sysext.service)... May 14 18:13:50.125009 systemd[1]: Reloading... May 14 18:13:50.194707 zram_generator::config[1263]: No configuration found. May 14 18:13:50.263133 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 14 18:13:50.265534 ldconfig[1195]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 14 18:13:50.326465 systemd[1]: Reloading finished in 201 ms. May 14 18:13:50.342676 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 14 18:13:50.344106 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 14 18:13:50.360131 systemd[1]: Starting ensure-sysext.service... May 14 18:13:50.362158 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 18:13:50.376263 systemd[1]: Reload requested from client PID 1285 ('systemctl') (unit ensure-sysext.service)... May 14 18:13:50.376279 systemd[1]: Reloading... May 14 18:13:50.380847 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 14 18:13:50.380875 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 14 18:13:50.381092 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 14 18:13:50.381288 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 14 18:13:50.382340 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 14 18:13:50.382673 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. May 14 18:13:50.382795 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. May 14 18:13:50.385446 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. May 14 18:13:50.385564 systemd-tmpfiles[1286]: Skipping /boot May 14 18:13:50.395379 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. May 14 18:13:50.395533 systemd-tmpfiles[1286]: Skipping /boot May 14 18:13:50.438683 zram_generator::config[1313]: No configuration found. May 14 18:13:50.513669 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 14 18:13:50.584856 systemd[1]: Reloading finished in 208 ms. May 14 18:13:50.607765 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 14 18:13:50.614176 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 18:13:50.621889 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 18:13:50.624550 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 14 18:13:50.627093 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 14 18:13:50.630442 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 18:13:50.648994 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 18:13:50.652772 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 14 18:13:50.662907 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 18:13:50.664670 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 18:13:50.668025 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 18:13:50.670893 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 18:13:50.672887 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 18:13:50.673011 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 14 18:13:50.675260 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 14 18:13:50.680122 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 14 18:13:50.682230 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 18:13:50.685972 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 18:13:50.690931 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 18:13:50.691576 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 18:13:50.693926 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 14 18:13:50.695783 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 18:13:50.695961 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 18:13:50.700720 systemd-udevd[1360]: Using default interface naming scheme 'v255'. May 14 18:13:50.705997 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 18:13:50.706976 augenrules[1384]: No rules May 14 18:13:50.708286 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 18:13:50.712068 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 18:13:50.724129 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 18:13:50.725521 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 18:13:50.725678 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 14 18:13:50.728923 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 14 18:13:50.730034 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 14 18:13:50.731202 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 14 18:13:50.732725 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 18:13:50.734745 systemd[1]: audit-rules.service: Deactivated successfully. May 14 18:13:50.736713 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 18:13:50.739438 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 14 18:13:50.741450 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 18:13:50.741630 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 18:13:50.743230 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 18:13:50.743394 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 18:13:50.747312 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 18:13:50.747476 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 18:13:50.752247 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 14 18:13:50.769748 systemd[1]: Finished ensure-sysext.service. May 14 18:13:50.779837 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 18:13:50.781555 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 18:13:50.782886 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 18:13:50.786224 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 18:13:50.796197 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 18:13:50.799751 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 18:13:50.802174 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 18:13:50.802218 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 14 18:13:50.804908 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 18:13:50.808916 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 14 18:13:50.810821 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 14 18:13:50.811457 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 18:13:50.811676 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 18:13:50.814127 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 18:13:50.814330 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 18:13:50.817255 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 18:13:50.817434 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 18:13:50.818132 augenrules[1432]: /sbin/augenrules: No change May 14 18:13:50.820095 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 18:13:50.820314 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 18:13:50.825566 augenrules[1456]: No rules May 14 18:13:50.827364 systemd[1]: audit-rules.service: Deactivated successfully. May 14 18:13:50.827588 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 18:13:50.836483 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 14 18:13:50.837438 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 18:13:50.837901 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 18:13:50.899956 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 14 18:13:50.903282 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 14 18:13:50.937039 systemd-resolved[1354]: Positive Trust Anchors: May 14 18:13:50.937053 systemd-resolved[1354]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 18:13:50.937085 systemd-resolved[1354]: 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 14 18:13:50.946325 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 14 18:13:50.948040 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 14 18:13:50.949522 systemd[1]: Reached target time-set.target - System Time Set. May 14 18:13:50.949991 systemd-networkd[1438]: lo: Link UP May 14 18:13:50.949994 systemd-networkd[1438]: lo: Gained carrier May 14 18:13:50.951127 systemd-networkd[1438]: Enumeration completed May 14 18:13:50.951320 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 18:13:50.951455 systemd-resolved[1354]: Defaulting to hostname 'linux'. May 14 18:13:50.951681 systemd-networkd[1438]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:13:50.951684 systemd-networkd[1438]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 18:13:50.952144 systemd-networkd[1438]: eth0: Link UP May 14 18:13:50.952257 systemd-networkd[1438]: eth0: Gained carrier May 14 18:13:50.952271 systemd-networkd[1438]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 18:13:50.954122 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 14 18:13:50.956752 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 14 18:13:50.957991 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 18:13:50.959719 systemd[1]: Reached target network.target - Network. May 14 18:13:50.960785 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 18:13:50.962170 systemd[1]: Reached target sysinit.target - System Initialization. May 14 18:13:50.963400 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 14 18:13:50.964723 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 14 18:13:50.966863 systemd-networkd[1438]: eth0: DHCPv4 address 10.0.0.116/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 14 18:13:50.966940 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 14 18:13:50.967681 systemd-timesyncd[1442]: Network configuration changed, trying to establish connection. May 14 18:13:50.968534 systemd-timesyncd[1442]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 14 18:13:50.968598 systemd-timesyncd[1442]: Initial clock synchronization to Wed 2025-05-14 18:13:51.063831 UTC. May 14 18:13:50.969444 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 14 18:13:50.970727 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 14 18:13:50.971944 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 14 18:13:50.971981 systemd[1]: Reached target paths.target - Path Units. May 14 18:13:50.972854 systemd[1]: Reached target timers.target - Timer Units. May 14 18:13:50.976065 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 14 18:13:50.978502 systemd[1]: Starting docker.socket - Docker Socket for the API... May 14 18:13:50.981990 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 14 18:13:50.983388 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 14 18:13:50.984597 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 14 18:13:50.988230 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 14 18:13:50.989898 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 14 18:13:50.992126 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 14 18:13:50.993591 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 14 18:13:50.995308 systemd[1]: Reached target sockets.target - Socket Units. May 14 18:13:50.996290 systemd[1]: Reached target basic.target - Basic System. May 14 18:13:50.997397 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 14 18:13:50.997431 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 14 18:13:50.999797 systemd[1]: Starting containerd.service - containerd container runtime... May 14 18:13:51.002109 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 14 18:13:51.007775 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 14 18:13:51.023867 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 14 18:13:51.026049 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 14 18:13:51.028728 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 14 18:13:51.033858 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 14 18:13:51.036844 jq[1500]: false May 14 18:13:51.036029 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 14 18:13:51.038844 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 14 18:13:51.052854 systemd[1]: Starting systemd-logind.service - User Login Management... May 14 18:13:51.054841 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 14 18:13:51.055357 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 14 18:13:51.058303 systemd[1]: Starting update-engine.service - Update Engine... May 14 18:13:51.060387 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 14 18:13:51.067305 extend-filesystems[1501]: Found loop3 May 14 18:13:51.067305 extend-filesystems[1501]: Found loop4 May 14 18:13:51.069492 extend-filesystems[1501]: Found loop5 May 14 18:13:51.069492 extend-filesystems[1501]: Found vda May 14 18:13:51.069492 extend-filesystems[1501]: Found vda1 May 14 18:13:51.069492 extend-filesystems[1501]: Found vda2 May 14 18:13:51.069492 extend-filesystems[1501]: Found vda3 May 14 18:13:51.069492 extend-filesystems[1501]: Found usr May 14 18:13:51.069492 extend-filesystems[1501]: Found vda4 May 14 18:13:51.069492 extend-filesystems[1501]: Found vda6 May 14 18:13:51.069492 extend-filesystems[1501]: Found vda7 May 14 18:13:51.069492 extend-filesystems[1501]: Found vda9 May 14 18:13:51.069492 extend-filesystems[1501]: Checking size of /dev/vda9 May 14 18:13:51.069970 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 14 18:13:51.072423 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 14 18:13:51.073709 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 14 18:13:51.096626 jq[1513]: true May 14 18:13:51.074022 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 14 18:13:51.074189 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 14 18:13:51.087042 systemd[1]: motdgen.service: Deactivated successfully. May 14 18:13:51.087289 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 14 18:13:51.100608 extend-filesystems[1501]: Resized partition /dev/vda9 May 14 18:13:51.100826 (ntainerd)[1519]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 14 18:13:51.105886 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 18:13:51.119955 jq[1521]: true May 14 18:13:51.120233 extend-filesystems[1532]: resize2fs 1.47.2 (1-Jan-2025) May 14 18:13:51.131675 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 14 18:13:51.137446 update_engine[1511]: I20250514 18:13:51.133678 1511 main.cc:92] Flatcar Update Engine starting May 14 18:13:51.136435 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 14 18:13:51.136006 dbus-daemon[1495]: [system] SELinux support is enabled May 14 18:13:51.144643 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 14 18:13:51.145744 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 14 18:13:51.148925 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 14 18:13:51.148951 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 14 18:13:51.156939 systemd[1]: Started update-engine.service - Update Engine. May 14 18:13:51.158483 update_engine[1511]: I20250514 18:13:51.157058 1511 update_check_scheduler.cc:74] Next update check in 3m15s May 14 18:13:51.164933 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 14 18:13:51.169793 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 14 18:13:51.191592 extend-filesystems[1532]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 14 18:13:51.191592 extend-filesystems[1532]: old_desc_blocks = 1, new_desc_blocks = 1 May 14 18:13:51.191592 extend-filesystems[1532]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 14 18:13:51.197502 extend-filesystems[1501]: Resized filesystem in /dev/vda9 May 14 18:13:51.195935 systemd[1]: extend-filesystems.service: Deactivated successfully. May 14 18:13:51.203904 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 14 18:13:51.207730 systemd-logind[1506]: Watching system buttons on /dev/input/event0 (Power Button) May 14 18:13:51.207987 systemd-logind[1506]: New seat seat0. May 14 18:13:51.210095 bash[1553]: Updated "/home/core/.ssh/authorized_keys" May 14 18:13:51.249515 systemd[1]: Started systemd-logind.service - User Login Management. May 14 18:13:51.251033 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 14 18:13:51.254704 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 18:13:51.262890 locksmithd[1538]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 14 18:13:51.265052 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 14 18:13:51.330511 containerd[1519]: time="2025-05-14T18:13:51Z" 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 14 18:13:51.332392 containerd[1519]: time="2025-05-14T18:13:51.332350915Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 14 18:13:51.341661 containerd[1519]: time="2025-05-14T18:13:51.341588913Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="11.495µs" May 14 18:13:51.342688 containerd[1519]: time="2025-05-14T18:13:51.341814086Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 14 18:13:51.342688 containerd[1519]: time="2025-05-14T18:13:51.341850474Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 14 18:13:51.342688 containerd[1519]: time="2025-05-14T18:13:51.342065811Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 14 18:13:51.342688 containerd[1519]: time="2025-05-14T18:13:51.342086009Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 14 18:13:51.342688 containerd[1519]: time="2025-05-14T18:13:51.342113129Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 14 18:13:51.342688 containerd[1519]: time="2025-05-14T18:13:51.342181292Z" 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 14 18:13:51.342688 containerd[1519]: time="2025-05-14T18:13:51.342194366Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 14 18:13:51.342942 containerd[1519]: time="2025-05-14T18:13:51.342915866Z" 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 14 18:13:51.343011 containerd[1519]: time="2025-05-14T18:13:51.342992044Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 14 18:13:51.343081 containerd[1519]: time="2025-05-14T18:13:51.343058062Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 14 18:13:51.343128 containerd[1519]: time="2025-05-14T18:13:51.343115701Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 14 18:13:51.343297 containerd[1519]: time="2025-05-14T18:13:51.343271779Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 14 18:13:51.343878 containerd[1519]: time="2025-05-14T18:13:51.343841896Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 14 18:13:51.343931 containerd[1519]: time="2025-05-14T18:13:51.343913662Z" 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 14 18:13:51.343931 containerd[1519]: time="2025-05-14T18:13:51.343927748Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 14 18:13:51.344601 containerd[1519]: time="2025-05-14T18:13:51.344573840Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 14 18:13:51.344923 containerd[1519]: time="2025-05-14T18:13:51.344901419Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 14 18:13:51.344998 containerd[1519]: time="2025-05-14T18:13:51.344981644Z" level=info msg="metadata content store policy set" policy=shared May 14 18:13:51.358716 containerd[1519]: time="2025-05-14T18:13:51.358643157Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 14 18:13:51.358776 containerd[1519]: time="2025-05-14T18:13:51.358733340Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 14 18:13:51.358776 containerd[1519]: time="2025-05-14T18:13:51.358752121Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 14 18:13:51.358776 containerd[1519]: time="2025-05-14T18:13:51.358765478Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 14 18:13:51.358834 containerd[1519]: time="2025-05-14T18:13:51.358819555Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 14 18:13:51.358868 containerd[1519]: time="2025-05-14T18:13:51.358837729Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 14 18:13:51.358868 containerd[1519]: time="2025-05-14T18:13:51.358851734Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 14 18:13:51.358868 containerd[1519]: time="2025-05-14T18:13:51.358863999Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 14 18:13:51.358922 containerd[1519]: time="2025-05-14T18:13:51.358876466Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 14 18:13:51.358922 containerd[1519]: time="2025-05-14T18:13:51.358888528Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 14 18:13:51.358922 containerd[1519]: time="2025-05-14T18:13:51.358900064Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 14 18:13:51.358922 containerd[1519]: time="2025-05-14T18:13:51.358913745Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 14 18:13:51.359113 containerd[1519]: time="2025-05-14T18:13:51.359080550Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 14 18:13:51.359113 containerd[1519]: time="2025-05-14T18:13:51.359111191Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 14 18:13:51.359161 containerd[1519]: time="2025-05-14T18:13:51.359127787Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 14 18:13:51.359161 containerd[1519]: time="2025-05-14T18:13:51.359144544Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 14 18:13:51.359161 containerd[1519]: time="2025-05-14T18:13:51.359155473Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 14 18:13:51.359216 containerd[1519]: time="2025-05-14T18:13:51.359165511Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 14 18:13:51.359216 containerd[1519]: time="2025-05-14T18:13:51.359179395Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 14 18:13:51.359216 containerd[1519]: time="2025-05-14T18:13:51.359203317Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 14 18:13:51.359216 containerd[1519]: time="2025-05-14T18:13:51.359215014Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 14 18:13:51.359288 containerd[1519]: time="2025-05-14T18:13:51.359226955Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 14 18:13:51.359288 containerd[1519]: time="2025-05-14T18:13:51.359243794Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 14 18:13:51.359477 containerd[1519]: time="2025-05-14T18:13:51.359452047Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 14 18:13:51.359477 containerd[1519]: time="2025-05-14T18:13:51.359472488Z" level=info msg="Start snapshots syncer" May 14 18:13:51.359520 containerd[1519]: time="2025-05-14T18:13:51.359501915Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 14 18:13:51.359791 containerd[1519]: time="2025-05-14T18:13:51.359742469Z" 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 14 18:13:51.359897 containerd[1519]: time="2025-05-14T18:13:51.359802334Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 14 18:13:51.359897 containerd[1519]: time="2025-05-14T18:13:51.359876852Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 14 18:13:51.360030 containerd[1519]: time="2025-05-14T18:13:51.359995044Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 14 18:13:51.360058 containerd[1519]: time="2025-05-14T18:13:51.360034995Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 14 18:13:51.360058 containerd[1519]: time="2025-05-14T18:13:51.360047502Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 14 18:13:51.360099 containerd[1519]: time="2025-05-14T18:13:51.360057622Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 14 18:13:51.360099 containerd[1519]: time="2025-05-14T18:13:51.360078062Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 14 18:13:51.360099 containerd[1519]: time="2025-05-14T18:13:51.360089194Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 14 18:13:51.360147 containerd[1519]: time="2025-05-14T18:13:51.360099799Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 14 18:13:51.360147 containerd[1519]: time="2025-05-14T18:13:51.360128173Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 14 18:13:51.360147 containerd[1519]: time="2025-05-14T18:13:51.360138737Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 14 18:13:51.360200 containerd[1519]: time="2025-05-14T18:13:51.360154888Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 14 18:13:51.360221 containerd[1519]: time="2025-05-14T18:13:51.360213984Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 14 18:13:51.360240 containerd[1519]: time="2025-05-14T18:13:51.360230620Z" 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 14 18:13:51.360260 containerd[1519]: time="2025-05-14T18:13:51.360239929Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 14 18:13:51.360260 containerd[1519]: time="2025-05-14T18:13:51.360250251Z" 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 14 18:13:51.360299 containerd[1519]: time="2025-05-14T18:13:51.360258873Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 14 18:13:51.360299 containerd[1519]: time="2025-05-14T18:13:51.360268385Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 14 18:13:51.360299 containerd[1519]: time="2025-05-14T18:13:51.360278868Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 14 18:13:51.360390 containerd[1519]: time="2025-05-14T18:13:51.360365246Z" level=info msg="runtime interface created" May 14 18:13:51.360390 containerd[1519]: time="2025-05-14T18:13:51.360386739Z" level=info msg="created NRI interface" May 14 18:13:51.360428 containerd[1519]: time="2025-05-14T18:13:51.360396818Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 14 18:13:51.360428 containerd[1519]: time="2025-05-14T18:13:51.360411025Z" level=info msg="Connect containerd service" May 14 18:13:51.360466 containerd[1519]: time="2025-05-14T18:13:51.360444419Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 14 18:13:51.361746 containerd[1519]: time="2025-05-14T18:13:51.361690742Z" 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 14 18:13:51.458247 sshd_keygen[1512]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 14 18:13:51.475854 containerd[1519]: time="2025-05-14T18:13:51.475741105Z" level=info msg="Start subscribing containerd event" May 14 18:13:51.475854 containerd[1519]: time="2025-05-14T18:13:51.475837237Z" level=info msg="Start recovering state" May 14 18:13:51.476015 containerd[1519]: time="2025-05-14T18:13:51.475788017Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 14 18:13:51.476015 containerd[1519]: time="2025-05-14T18:13:51.475939725Z" level=info msg="Start event monitor" May 14 18:13:51.476015 containerd[1519]: time="2025-05-14T18:13:51.475976761Z" level=info msg="Start cni network conf syncer for default" May 14 18:13:51.476015 containerd[1519]: time="2025-05-14T18:13:51.475984290Z" level=info msg="Start streaming server" May 14 18:13:51.476015 containerd[1519]: time="2025-05-14T18:13:51.475992183Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 14 18:13:51.476015 containerd[1519]: time="2025-05-14T18:13:51.475998578Z" level=info msg="runtime interface starting up..." May 14 18:13:51.476015 containerd[1519]: time="2025-05-14T18:13:51.476004690Z" level=info msg="starting plugins..." May 14 18:13:51.476204 containerd[1519]: time="2025-05-14T18:13:51.476020274Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 14 18:13:51.476204 containerd[1519]: time="2025-05-14T18:13:51.476174248Z" level=info msg=serving... address=/run/containerd/containerd.sock May 14 18:13:51.477271 containerd[1519]: time="2025-05-14T18:13:51.476792330Z" level=info msg="containerd successfully booted in 0.146723s" May 14 18:13:51.476371 systemd[1]: Started containerd.service - containerd container runtime. May 14 18:13:51.487640 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 14 18:13:51.490868 systemd[1]: Starting issuegen.service - Generate /run/issue... May 14 18:13:51.510918 systemd[1]: issuegen.service: Deactivated successfully. May 14 18:13:51.511139 systemd[1]: Finished issuegen.service - Generate /run/issue. May 14 18:13:51.514201 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 14 18:13:51.544425 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 14 18:13:51.549616 systemd[1]: Started getty@tty1.service - Getty on tty1. May 14 18:13:51.552132 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 14 18:13:51.553750 systemd[1]: Reached target getty.target - Login Prompts. May 14 18:13:52.358798 systemd-networkd[1438]: eth0: Gained IPv6LL May 14 18:13:52.361301 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 14 18:13:52.363210 systemd[1]: Reached target network-online.target - Network is Online. May 14 18:13:52.367353 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 14 18:13:52.370271 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 14 18:13:52.389635 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 14 18:13:52.406445 systemd[1]: coreos-metadata.service: Deactivated successfully. May 14 18:13:52.406719 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 14 18:13:52.408816 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 14 18:13:52.422238 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 14 18:13:52.864982 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 14 18:13:52.866693 systemd[1]: Reached target multi-user.target - Multi-User System. May 14 18:13:52.869133 (kubelet)[1625]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 14 18:13:52.869472 systemd[1]: Startup finished in 2.178s (kernel) + 4.662s (initrd) + 3.621s (userspace) = 10.462s. May 14 18:13:53.290309 kubelet[1625]: E0514 18:13:53.290185 1625 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 14 18:13:53.292465 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 14 18:13:53.292607 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 14 18:13:53.292964 systemd[1]: kubelet.service: Consumed 781ms CPU time, 231.9M memory peak. May 14 18:13:57.888283 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 14 18:13:57.889430 systemd[1]: Started sshd@0-10.0.0.116:22-10.0.0.1:33238.service - OpenSSH per-connection server daemon (10.0.0.1:33238). May 14 18:13:57.970164 sshd[1639]: Accepted publickey for core from 10.0.0.1 port 33238 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 18:13:57.973010 sshd-session[1639]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:13:57.979422 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 14 18:13:57.980440 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 14 18:13:57.985911 systemd-logind[1506]: New session 1 of user core. May 14 18:13:58.004766 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 14 18:13:58.007318 systemd[1]: Starting user@500.service - User Manager for UID 500... May 14 18:13:58.024055 (systemd)[1643]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 14 18:13:58.026365 systemd-logind[1506]: New session c1 of user core. May 14 18:13:58.132635 systemd[1643]: Queued start job for default target default.target. May 14 18:13:58.152609 systemd[1643]: Created slice app.slice - User Application Slice. May 14 18:13:58.152639 systemd[1643]: Reached target paths.target - Paths. May 14 18:13:58.152698 systemd[1643]: Reached target timers.target - Timers. May 14 18:13:58.153915 systemd[1643]: Starting dbus.socket - D-Bus User Message Bus Socket... May 14 18:13:58.163391 systemd[1643]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 14 18:13:58.163469 systemd[1643]: Reached target sockets.target - Sockets. May 14 18:13:58.163510 systemd[1643]: Reached target basic.target - Basic System. May 14 18:13:58.163546 systemd[1643]: Reached target default.target - Main User Target. May 14 18:13:58.163578 systemd[1643]: Startup finished in 131ms. May 14 18:13:58.163741 systemd[1]: Started user@500.service - User Manager for UID 500. May 14 18:13:58.165085 systemd[1]: Started session-1.scope - Session 1 of User core. May 14 18:13:58.234398 systemd[1]: Started sshd@1-10.0.0.116:22-10.0.0.1:33246.service - OpenSSH per-connection server daemon (10.0.0.1:33246). May 14 18:13:58.286006 sshd[1654]: Accepted publickey for core from 10.0.0.1 port 33246 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 18:13:58.287330 sshd-session[1654]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:13:58.291705 systemd-logind[1506]: New session 2 of user core. May 14 18:13:58.304854 systemd[1]: Started session-2.scope - Session 2 of User core. May 14 18:13:58.357388 sshd[1656]: Connection closed by 10.0.0.1 port 33246 May 14 18:13:58.357832 sshd-session[1654]: pam_unix(sshd:session): session closed for user core May 14 18:13:58.377672 systemd[1]: sshd@1-10.0.0.116:22-10.0.0.1:33246.service: Deactivated successfully. May 14 18:13:58.379951 systemd[1]: session-2.scope: Deactivated successfully. May 14 18:13:58.380852 systemd-logind[1506]: Session 2 logged out. Waiting for processes to exit. May 14 18:13:58.383035 systemd[1]: Started sshd@2-10.0.0.116:22-10.0.0.1:33254.service - OpenSSH per-connection server daemon (10.0.0.1:33254). May 14 18:13:58.383791 systemd-logind[1506]: Removed session 2. May 14 18:13:58.455689 sshd[1662]: Accepted publickey for core from 10.0.0.1 port 33254 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 18:13:58.456894 sshd-session[1662]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:13:58.461759 systemd-logind[1506]: New session 3 of user core. May 14 18:13:58.469824 systemd[1]: Started session-3.scope - Session 3 of User core. May 14 18:13:58.519862 sshd[1664]: Connection closed by 10.0.0.1 port 33254 May 14 18:13:58.520170 sshd-session[1662]: pam_unix(sshd:session): session closed for user core May 14 18:13:58.534763 systemd[1]: sshd@2-10.0.0.116:22-10.0.0.1:33254.service: Deactivated successfully. May 14 18:13:58.537133 systemd[1]: session-3.scope: Deactivated successfully. May 14 18:13:58.537917 systemd-logind[1506]: Session 3 logged out. Waiting for processes to exit. May 14 18:13:58.540373 systemd[1]: Started sshd@3-10.0.0.116:22-10.0.0.1:33258.service - OpenSSH per-connection server daemon (10.0.0.1:33258). May 14 18:13:58.541102 systemd-logind[1506]: Removed session 3. May 14 18:13:58.597245 sshd[1670]: Accepted publickey for core from 10.0.0.1 port 33258 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 18:13:58.598748 sshd-session[1670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:13:58.603489 systemd-logind[1506]: New session 4 of user core. May 14 18:13:58.615726 systemd[1]: Started session-4.scope - Session 4 of User core. May 14 18:13:58.667930 sshd[1673]: Connection closed by 10.0.0.1 port 33258 May 14 18:13:58.669174 sshd-session[1670]: pam_unix(sshd:session): session closed for user core May 14 18:13:58.679959 systemd[1]: sshd@3-10.0.0.116:22-10.0.0.1:33258.service: Deactivated successfully. May 14 18:13:58.681976 systemd[1]: session-4.scope: Deactivated successfully. May 14 18:13:58.684848 systemd-logind[1506]: Session 4 logged out. Waiting for processes to exit. May 14 18:13:58.687249 systemd[1]: Started sshd@4-10.0.0.116:22-10.0.0.1:33270.service - OpenSSH per-connection server daemon (10.0.0.1:33270). May 14 18:13:58.691608 systemd-logind[1506]: Removed session 4. May 14 18:13:58.743796 sshd[1679]: Accepted publickey for core from 10.0.0.1 port 33270 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 18:13:58.745461 sshd-session[1679]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 18:13:58.750451 systemd-logind[1506]: New session 5 of user core. May 14 18:13:58.763806 systemd[1]: Started session-5.scope - Session 5 of User core. May 14 18:13:58.821564 sudo[1682]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 14 18:13:58.821855 sudo[1682]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 18:13:58.833029 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 14 18:13:58.860763 systemd[1]: coreos-metadata.service: Deactivated successfully. May 14 18:13:58.861752 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 14 18:13:59.316464 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 14 18:13:59.316701 systemd[1]: kubelet.service: Consumed 781ms CPU time, 231.9M memory peak. May 14 18:13:59.319898 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 14 18:13:59.341293 systemd[1]: Reload requested from client PID 1724 ('systemctl') (unit session-5.scope)... May 14 18:13:59.341309 systemd[1]: Reloading... May 14 18:13:59.412685 zram_generator::config[1766]: No configuration found. May 14 18:13:59.567096 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 14 18:13:59.651591 systemd[1]: Reloading finished in 309 ms. May 14 18:13:59.711123 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM May 14 18:13:59.711257 systemd[1]: kubelet.service: Failed with result 'signal'. May 14 18:13:59.711555 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 14 18:13:59.711603 systemd[1]: kubelet.service: Consumed 81ms CPU time, 82.4M memory peak. May 14 18:13:59.713152 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 14 18:13:59.815268 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 14 18:13:59.832998 (kubelet)[1811]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 14 18:13:59.867235 kubelet[1811]: 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 14 18:13:59.867235 kubelet[1811]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 14 18:13:59.867235 kubelet[1811]: 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 14 18:13:59.867554 kubelet[1811]: I0514 18:13:59.867393 1811 server.go:206] "--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 14 18:14:00.746889 kubelet[1811]: I0514 18:14:00.746842 1811 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" May 14 18:14:00.746889 kubelet[1811]: I0514 18:14:00.746875 1811 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 14 18:14:00.747138 kubelet[1811]: I0514 18:14:00.747112 1811 server.go:929] "Client rotation is on, will bootstrap in background" May 14 18:14:00.783391 kubelet[1811]: I0514 18:14:00.782981 1811 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 14 18:14:00.793179 kubelet[1811]: I0514 18:14:00.793150 1811 server.go:1426] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" May 14 18:14:00.796911 kubelet[1811]: I0514 18:14:00.796880 1811 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 14 18:14:00.798120 kubelet[1811]: I0514 18:14:00.798094 1811 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 14 18:14:00.798378 kubelet[1811]: I0514 18:14:00.798330 1811 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 14 18:14:00.798635 kubelet[1811]: I0514 18:14:00.798435 1811 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.116","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 14 18:14:00.798972 kubelet[1811]: I0514 18:14:00.798953 1811 topology_manager.go:138] "Creating topology manager with none policy" May 14 18:14:00.799032 kubelet[1811]: I0514 18:14:00.799024 1811 container_manager_linux.go:300] "Creating device plugin manager" May 14 18:14:00.799278 kubelet[1811]: I0514 18:14:00.799259 1811 state_mem.go:36] "Initialized new in-memory state store" May 14 18:14:00.800304 kubelet[1811]: I0514 18:14:00.800284 1811 kubelet.go:408] "Attempting to sync node with API server" May 14 18:14:00.800383 kubelet[1811]: I0514 18:14:00.800374 1811 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 14 18:14:00.800530 kubelet[1811]: I0514 18:14:00.800520 1811 kubelet.go:314] "Adding apiserver pod source" May 14 18:14:00.800586 kubelet[1811]: I0514 18:14:00.800578 1811 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 14 18:14:00.800687 kubelet[1811]: E0514 18:14:00.800655 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:00.800725 kubelet[1811]: E0514 18:14:00.800712 1811 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:00.806926 kubelet[1811]: I0514 18:14:00.806905 1811 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" May 14 18:14:00.808331 kubelet[1811]: W0514 18:14:00.808249 1811 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope May 14 18:14:00.808331 kubelet[1811]: E0514 18:14:00.808300 1811 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" May 14 18:14:00.808816 kubelet[1811]: W0514 18:14:00.808757 1811 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.116" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 14 18:14:00.808816 kubelet[1811]: E0514 18:14:00.808793 1811 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.116\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 14 18:14:00.809017 kubelet[1811]: I0514 18:14:00.808999 1811 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 14 18:14:00.809265 kubelet[1811]: W0514 18:14:00.809243 1811 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 14 18:14:00.810906 kubelet[1811]: I0514 18:14:00.810109 1811 server.go:1269] "Started kubelet" May 14 18:14:00.810906 kubelet[1811]: I0514 18:14:00.810545 1811 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 14 18:14:00.810906 kubelet[1811]: I0514 18:14:00.810858 1811 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 14 18:14:00.810906 kubelet[1811]: I0514 18:14:00.810900 1811 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 14 18:14:00.812215 kubelet[1811]: I0514 18:14:00.811937 1811 server.go:460] "Adding debug handlers to kubelet server" May 14 18:14:00.812215 kubelet[1811]: I0514 18:14:00.812068 1811 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 14 18:14:00.812801 kubelet[1811]: I0514 18:14:00.812765 1811 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 14 18:14:00.813744 kubelet[1811]: I0514 18:14:00.813725 1811 volume_manager.go:289] "Starting Kubelet Volume Manager" May 14 18:14:00.813928 kubelet[1811]: I0514 18:14:00.813915 1811 desired_state_of_world_populator.go:146] "Desired state populator starts to run" May 14 18:14:00.814203 kubelet[1811]: E0514 18:14:00.814170 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:00.814285 kubelet[1811]: I0514 18:14:00.814271 1811 reconciler.go:26] "Reconciler: start to sync state" May 14 18:14:00.814394 kubelet[1811]: I0514 18:14:00.814371 1811 factory.go:221] Registration of the systemd container factory successfully May 14 18:14:00.814534 kubelet[1811]: I0514 18:14:00.814459 1811 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 14 18:14:00.815990 kubelet[1811]: E0514 18:14:00.815934 1811 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 14 18:14:00.817894 kubelet[1811]: I0514 18:14:00.817866 1811 factory.go:221] Registration of the containerd container factory successfully May 14 18:14:00.826019 kubelet[1811]: E0514 18:14:00.825981 1811 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.116\" not found" node="10.0.0.116" May 14 18:14:00.830251 kubelet[1811]: I0514 18:14:00.830235 1811 cpu_manager.go:214] "Starting CPU manager" policy="none" May 14 18:14:00.830356 kubelet[1811]: I0514 18:14:00.830344 1811 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 14 18:14:00.830423 kubelet[1811]: I0514 18:14:00.830415 1811 state_mem.go:36] "Initialized new in-memory state store" May 14 18:14:00.902546 kubelet[1811]: I0514 18:14:00.901774 1811 policy_none.go:49] "None policy: Start" May 14 18:14:00.903755 kubelet[1811]: I0514 18:14:00.903733 1811 memory_manager.go:170] "Starting memorymanager" policy="None" May 14 18:14:00.903827 kubelet[1811]: I0514 18:14:00.903761 1811 state_mem.go:35] "Initializing new in-memory state store" May 14 18:14:00.912638 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 14 18:14:00.914272 kubelet[1811]: E0514 18:14:00.914246 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:00.922634 kubelet[1811]: I0514 18:14:00.922592 1811 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 14 18:14:00.923915 kubelet[1811]: I0514 18:14:00.923823 1811 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 14 18:14:00.923915 kubelet[1811]: I0514 18:14:00.923859 1811 status_manager.go:217] "Starting to sync pod status with apiserver" May 14 18:14:00.923915 kubelet[1811]: I0514 18:14:00.923878 1811 kubelet.go:2321] "Starting kubelet main sync loop" May 14 18:14:00.924013 kubelet[1811]: E0514 18:14:00.923924 1811 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" May 14 18:14:00.928228 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 14 18:14:00.931244 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 14 18:14:00.941885 kubelet[1811]: I0514 18:14:00.941837 1811 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 14 18:14:00.942549 kubelet[1811]: I0514 18:14:00.942068 1811 eviction_manager.go:189] "Eviction manager: starting control loop" May 14 18:14:00.942549 kubelet[1811]: I0514 18:14:00.942086 1811 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 14 18:14:00.942549 kubelet[1811]: I0514 18:14:00.942358 1811 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 14 18:14:00.943990 kubelet[1811]: E0514 18:14:00.943962 1811 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.116\" not found" May 14 18:14:01.043778 kubelet[1811]: I0514 18:14:01.043685 1811 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.116" May 14 18:14:01.053912 kubelet[1811]: I0514 18:14:01.053868 1811 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.116" May 14 18:14:01.053912 kubelet[1811]: E0514 18:14:01.053907 1811 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.116\": node \"10.0.0.116\" not found" May 14 18:14:01.057593 kubelet[1811]: I0514 18:14:01.057557 1811 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 14 18:14:01.057987 containerd[1519]: time="2025-05-14T18:14:01.057951083Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 14 18:14:01.058292 kubelet[1811]: I0514 18:14:01.058173 1811 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 14 18:14:01.064527 kubelet[1811]: E0514 18:14:01.064497 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.164692 kubelet[1811]: E0514 18:14:01.164618 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.265701 kubelet[1811]: E0514 18:14:01.265665 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.337315 sudo[1682]: pam_unix(sudo:session): session closed for user root May 14 18:14:01.338598 sshd[1681]: Connection closed by 10.0.0.1 port 33270 May 14 18:14:01.339084 sshd-session[1679]: pam_unix(sshd:session): session closed for user core May 14 18:14:01.341862 systemd[1]: session-5.scope: Deactivated successfully. May 14 18:14:01.342099 systemd[1]: session-5.scope: Consumed 451ms CPU time, 74.7M memory peak. May 14 18:14:01.343182 systemd[1]: sshd@4-10.0.0.116:22-10.0.0.1:33270.service: Deactivated successfully. May 14 18:14:01.345957 systemd-logind[1506]: Session 5 logged out. Waiting for processes to exit. May 14 18:14:01.348215 systemd-logind[1506]: Removed session 5. May 14 18:14:01.366811 kubelet[1811]: E0514 18:14:01.366781 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.467711 kubelet[1811]: E0514 18:14:01.467682 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.568526 kubelet[1811]: E0514 18:14:01.568484 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.669448 kubelet[1811]: E0514 18:14:01.669353 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.749194 kubelet[1811]: I0514 18:14:01.749159 1811 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 14 18:14:01.749386 kubelet[1811]: W0514 18:14:01.749342 1811 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 14 18:14:01.749425 kubelet[1811]: W0514 18:14:01.749349 1811 reflector.go:484] 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 14 18:14:01.770443 kubelet[1811]: E0514 18:14:01.770414 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.800822 kubelet[1811]: E0514 18:14:01.800750 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:01.870901 kubelet[1811]: E0514 18:14:01.870862 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:01.971276 kubelet[1811]: E0514 18:14:01.971151 1811 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.116\" not found" May 14 18:14:02.800946 kubelet[1811]: I0514 18:14:02.800894 1811 apiserver.go:52] "Watching apiserver" May 14 18:14:02.800946 kubelet[1811]: E0514 18:14:02.800939 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:02.812703 systemd[1]: Created slice kubepods-besteffort-pod11f39c4c_39a8_40b5_8acc_0bd04a17dcfb.slice - libcontainer container kubepods-besteffort-pod11f39c4c_39a8_40b5_8acc_0bd04a17dcfb.slice. May 14 18:14:02.814739 kubelet[1811]: I0514 18:14:02.814703 1811 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" May 14 18:14:02.827644 kubelet[1811]: I0514 18:14:02.827600 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/dc673b40-a576-43dd-8dea-63ddb6354f60-cni\") pod \"kube-flannel-ds-k4m2q\" (UID: \"dc673b40-a576-43dd-8dea-63ddb6354f60\") " pod="kube-flannel/kube-flannel-ds-k4m2q" May 14 18:14:02.827714 kubelet[1811]: I0514 18:14:02.827658 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/dc673b40-a576-43dd-8dea-63ddb6354f60-xtables-lock\") pod \"kube-flannel-ds-k4m2q\" (UID: \"dc673b40-a576-43dd-8dea-63ddb6354f60\") " pod="kube-flannel/kube-flannel-ds-k4m2q" May 14 18:14:02.827714 kubelet[1811]: I0514 18:14:02.827680 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/11f39c4c-39a8-40b5-8acc-0bd04a17dcfb-xtables-lock\") pod \"kube-proxy-5rmxf\" (UID: \"11f39c4c-39a8-40b5-8acc-0bd04a17dcfb\") " pod="kube-system/kube-proxy-5rmxf" May 14 18:14:02.827714 kubelet[1811]: I0514 18:14:02.827700 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/11f39c4c-39a8-40b5-8acc-0bd04a17dcfb-lib-modules\") pod \"kube-proxy-5rmxf\" (UID: \"11f39c4c-39a8-40b5-8acc-0bd04a17dcfb\") " pod="kube-system/kube-proxy-5rmxf" May 14 18:14:02.827783 kubelet[1811]: I0514 18:14:02.827717 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4h7jp\" (UniqueName: \"kubernetes.io/projected/11f39c4c-39a8-40b5-8acc-0bd04a17dcfb-kube-api-access-4h7jp\") pod \"kube-proxy-5rmxf\" (UID: \"11f39c4c-39a8-40b5-8acc-0bd04a17dcfb\") " pod="kube-system/kube-proxy-5rmxf" May 14 18:14:02.827783 kubelet[1811]: I0514 18:14:02.827731 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/dc673b40-a576-43dd-8dea-63ddb6354f60-run\") pod \"kube-flannel-ds-k4m2q\" (UID: \"dc673b40-a576-43dd-8dea-63ddb6354f60\") " pod="kube-flannel/kube-flannel-ds-k4m2q" May 14 18:14:02.827783 kubelet[1811]: I0514 18:14:02.827745 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/dc673b40-a576-43dd-8dea-63ddb6354f60-cni-plugin\") pod \"kube-flannel-ds-k4m2q\" (UID: \"dc673b40-a576-43dd-8dea-63ddb6354f60\") " pod="kube-flannel/kube-flannel-ds-k4m2q" May 14 18:14:02.827783 kubelet[1811]: I0514 18:14:02.827762 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/dc673b40-a576-43dd-8dea-63ddb6354f60-flannel-cfg\") pod \"kube-flannel-ds-k4m2q\" (UID: \"dc673b40-a576-43dd-8dea-63ddb6354f60\") " pod="kube-flannel/kube-flannel-ds-k4m2q" May 14 18:14:02.827783 kubelet[1811]: I0514 18:14:02.827778 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s299x\" (UniqueName: \"kubernetes.io/projected/dc673b40-a576-43dd-8dea-63ddb6354f60-kube-api-access-s299x\") pod \"kube-flannel-ds-k4m2q\" (UID: \"dc673b40-a576-43dd-8dea-63ddb6354f60\") " pod="kube-flannel/kube-flannel-ds-k4m2q" May 14 18:14:02.827876 kubelet[1811]: I0514 18:14:02.827792 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/11f39c4c-39a8-40b5-8acc-0bd04a17dcfb-kube-proxy\") pod \"kube-proxy-5rmxf\" (UID: \"11f39c4c-39a8-40b5-8acc-0bd04a17dcfb\") " pod="kube-system/kube-proxy-5rmxf" May 14 18:14:02.839620 systemd[1]: Created slice kubepods-burstable-poddc673b40_a576_43dd_8dea_63ddb6354f60.slice - libcontainer container kubepods-burstable-poddc673b40_a576_43dd_8dea_63ddb6354f60.slice. May 14 18:14:03.139542 containerd[1519]: time="2025-05-14T18:14:03.139118190Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5rmxf,Uid:11f39c4c-39a8-40b5-8acc-0bd04a17dcfb,Namespace:kube-system,Attempt:0,}" May 14 18:14:03.152570 containerd[1519]: time="2025-05-14T18:14:03.152525588Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-k4m2q,Uid:dc673b40-a576-43dd-8dea-63ddb6354f60,Namespace:kube-flannel,Attempt:0,}" May 14 18:14:03.801111 kubelet[1811]: E0514 18:14:03.801058 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:04.171507 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2111742751.mount: Deactivated successfully. May 14 18:14:04.177427 containerd[1519]: time="2025-05-14T18:14:04.177377598Z" 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 14 18:14:04.179253 containerd[1519]: time="2025-05-14T18:14:04.179216213Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" May 14 18:14:04.179902 containerd[1519]: time="2025-05-14T18:14:04.179794786Z" 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 14 18:14:04.181555 containerd[1519]: time="2025-05-14T18:14:04.180879821Z" 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 14 18:14:04.182137 containerd[1519]: time="2025-05-14T18:14:04.182116534Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" May 14 18:14:04.183312 containerd[1519]: time="2025-05-14T18:14:04.183277368Z" 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 14 18:14:04.184525 containerd[1519]: time="2025-05-14T18:14:04.184495242Z" 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 1.02673668s" May 14 18:14:04.185338 containerd[1519]: time="2025-05-14T18:14:04.185161759Z" 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 1.040647886s" May 14 18:14:04.201805 containerd[1519]: time="2025-05-14T18:14:04.201750341Z" level=info msg="connecting to shim 99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468" address="unix:///run/containerd/s/7e165ddf942b1b24482a46ef94e146c4058ea54bd8356b6b4476bdfe234487be" namespace=k8s.io protocol=ttrpc version=3 May 14 18:14:04.204796 containerd[1519]: time="2025-05-14T18:14:04.204758809Z" level=info msg="connecting to shim f34f3c40c63d522353f3917a48734ca9f52ff2651525b965b9c1f6a709ef51f4" address="unix:///run/containerd/s/51839f24ac6c2f2dd9686a705b6f03e309386dc2291337242467af54f368f3fe" namespace=k8s.io protocol=ttrpc version=3 May 14 18:14:04.225898 systemd[1]: Started cri-containerd-99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468.scope - libcontainer container 99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468. May 14 18:14:04.230106 systemd[1]: Started cri-containerd-f34f3c40c63d522353f3917a48734ca9f52ff2651525b965b9c1f6a709ef51f4.scope - libcontainer container f34f3c40c63d522353f3917a48734ca9f52ff2651525b965b9c1f6a709ef51f4. May 14 18:14:04.253706 containerd[1519]: time="2025-05-14T18:14:04.253576165Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5rmxf,Uid:11f39c4c-39a8-40b5-8acc-0bd04a17dcfb,Namespace:kube-system,Attempt:0,} returns sandbox id \"f34f3c40c63d522353f3917a48734ca9f52ff2651525b965b9c1f6a709ef51f4\"" May 14 18:14:04.256653 containerd[1519]: time="2025-05-14T18:14:04.256613734Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\"" May 14 18:14:04.259238 containerd[1519]: time="2025-05-14T18:14:04.259197191Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-k4m2q,Uid:dc673b40-a576-43dd-8dea-63ddb6354f60,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468\"" May 14 18:14:04.802196 kubelet[1811]: E0514 18:14:04.802157 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:05.476210 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1727863525.mount: Deactivated successfully. May 14 18:14:05.686872 containerd[1519]: time="2025-05-14T18:14:05.686821323Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:05.688304 containerd[1519]: time="2025-05-14T18:14:05.688274270Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.8: active requests=0, bytes read=26871919" May 14 18:14:05.689177 containerd[1519]: time="2025-05-14T18:14:05.689148554Z" level=info msg="ImageCreate event name:\"sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:05.691290 containerd[1519]: time="2025-05-14T18:14:05.691261672Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:05.691796 containerd[1519]: time="2025-05-14T18:14:05.691766278Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.8\" with image id \"sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581\", repo tag \"registry.k8s.io/kube-proxy:v1.31.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\", size \"26870936\" in 1.435088131s" May 14 18:14:05.691796 containerd[1519]: time="2025-05-14T18:14:05.691796493Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\" returns image reference \"sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581\"" May 14 18:14:05.692837 containerd[1519]: time="2025-05-14T18:14:05.692817006Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 14 18:14:05.694244 containerd[1519]: time="2025-05-14T18:14:05.694149050Z" level=info msg="CreateContainer within sandbox \"f34f3c40c63d522353f3917a48734ca9f52ff2651525b965b9c1f6a709ef51f4\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 14 18:14:05.701008 containerd[1519]: time="2025-05-14T18:14:05.700969126Z" level=info msg="Container f405d67cc345eb6920b61fbf5079adee7ae8d6daf3570697dc6753c6bc55d0ad: CDI devices from CRI Config.CDIDevices: []" May 14 18:14:05.707568 containerd[1519]: time="2025-05-14T18:14:05.707525478Z" level=info msg="CreateContainer within sandbox \"f34f3c40c63d522353f3917a48734ca9f52ff2651525b965b9c1f6a709ef51f4\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"f405d67cc345eb6920b61fbf5079adee7ae8d6daf3570697dc6753c6bc55d0ad\"" May 14 18:14:05.708165 containerd[1519]: time="2025-05-14T18:14:05.708142851Z" level=info msg="StartContainer for \"f405d67cc345eb6920b61fbf5079adee7ae8d6daf3570697dc6753c6bc55d0ad\"" May 14 18:14:05.709893 containerd[1519]: time="2025-05-14T18:14:05.709861725Z" level=info msg="connecting to shim f405d67cc345eb6920b61fbf5079adee7ae8d6daf3570697dc6753c6bc55d0ad" address="unix:///run/containerd/s/51839f24ac6c2f2dd9686a705b6f03e309386dc2291337242467af54f368f3fe" protocol=ttrpc version=3 May 14 18:14:05.731792 systemd[1]: Started cri-containerd-f405d67cc345eb6920b61fbf5079adee7ae8d6daf3570697dc6753c6bc55d0ad.scope - libcontainer container f405d67cc345eb6920b61fbf5079adee7ae8d6daf3570697dc6753c6bc55d0ad. May 14 18:14:05.764776 containerd[1519]: time="2025-05-14T18:14:05.764631313Z" level=info msg="StartContainer for \"f405d67cc345eb6920b61fbf5079adee7ae8d6daf3570697dc6753c6bc55d0ad\" returns successfully" May 14 18:14:05.802510 kubelet[1811]: E0514 18:14:05.802456 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:05.949070 kubelet[1811]: I0514 18:14:05.948996 1811 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-5rmxf" podStartSLOduration=3.512434294 podStartE2EDuration="4.948981095s" podCreationTimestamp="2025-05-14 18:14:01 +0000 UTC" firstStartedPulling="2025-05-14 18:14:04.256083542 +0000 UTC m=+4.419939459" lastFinishedPulling="2025-05-14 18:14:05.692630303 +0000 UTC m=+5.856486260" observedRunningTime="2025-05-14 18:14:05.948718373 +0000 UTC m=+6.112574330" watchObservedRunningTime="2025-05-14 18:14:05.948981095 +0000 UTC m=+6.112837052" May 14 18:14:06.802835 kubelet[1811]: E0514 18:14:06.802781 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:06.915755 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount557714746.mount: Deactivated successfully. May 14 18:14:06.944927 containerd[1519]: time="2025-05-14T18:14:06.944755470Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:06.945681 containerd[1519]: time="2025-05-14T18:14:06.945470498Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" May 14 18:14:06.946333 containerd[1519]: time="2025-05-14T18:14:06.946301753Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:06.948279 containerd[1519]: time="2025-05-14T18:14:06.948247999Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:06.949698 containerd[1519]: time="2025-05-14T18:14:06.949659266Z" 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.256798665s" May 14 18:14:06.949922 containerd[1519]: time="2025-05-14T18:14:06.949828177Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 14 18:14:06.951537 containerd[1519]: time="2025-05-14T18:14:06.951512883Z" level=info msg="CreateContainer within sandbox \"99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 14 18:14:06.958656 containerd[1519]: time="2025-05-14T18:14:06.958196978Z" level=info msg="Container 709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc: CDI devices from CRI Config.CDIDevices: []" May 14 18:14:06.963063 containerd[1519]: time="2025-05-14T18:14:06.963027657Z" level=info msg="CreateContainer within sandbox \"99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc\"" May 14 18:14:06.963486 containerd[1519]: time="2025-05-14T18:14:06.963462796Z" level=info msg="StartContainer for \"709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc\"" May 14 18:14:06.964398 containerd[1519]: time="2025-05-14T18:14:06.964301823Z" level=info msg="connecting to shim 709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc" address="unix:///run/containerd/s/7e165ddf942b1b24482a46ef94e146c4058ea54bd8356b6b4476bdfe234487be" protocol=ttrpc version=3 May 14 18:14:06.981810 systemd[1]: Started cri-containerd-709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc.scope - libcontainer container 709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc. May 14 18:14:07.007581 systemd[1]: cri-containerd-709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc.scope: Deactivated successfully. May 14 18:14:07.009462 containerd[1519]: time="2025-05-14T18:14:07.009262547Z" level=info msg="StartContainer for \"709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc\" returns successfully" May 14 18:14:07.009548 containerd[1519]: time="2025-05-14T18:14:07.009501884Z" level=info msg="received exit event container_id:\"709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc\" id:\"709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc\" pid:2145 exited_at:{seconds:1747246447 nanos:9007629}" May 14 18:14:07.009845 containerd[1519]: time="2025-05-14T18:14:07.009782558Z" level=info msg="TaskExit event in podsandbox handler container_id:\"709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc\" id:\"709d721145834336e9b240c526e0f7ee876712bb339aa7e3cfc87004bf7ff0dc\" pid:2145 exited_at:{seconds:1747246447 nanos:9007629}" May 14 18:14:07.803709 kubelet[1811]: E0514 18:14:07.803669 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:07.942317 containerd[1519]: time="2025-05-14T18:14:07.942271474Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 14 18:14:08.804016 kubelet[1811]: E0514 18:14:08.803978 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:09.190755 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount213864000.mount: Deactivated successfully. May 14 18:14:09.652117 containerd[1519]: time="2025-05-14T18:14:09.651940105Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:09.652616 containerd[1519]: time="2025-05-14T18:14:09.652514684Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" May 14 18:14:09.653648 containerd[1519]: time="2025-05-14T18:14:09.653587158Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:09.656826 containerd[1519]: time="2025-05-14T18:14:09.656774790Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:09.657674 containerd[1519]: time="2025-05-14T18:14:09.657635677Z" 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.715324669s" May 14 18:14:09.657732 containerd[1519]: time="2025-05-14T18:14:09.657677121Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 14 18:14:09.659784 containerd[1519]: time="2025-05-14T18:14:09.659746669Z" level=info msg="CreateContainer within sandbox \"99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 14 18:14:09.665305 containerd[1519]: time="2025-05-14T18:14:09.664685506Z" level=info msg="Container 9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce: CDI devices from CRI Config.CDIDevices: []" May 14 18:14:09.671303 containerd[1519]: time="2025-05-14T18:14:09.671260184Z" level=info msg="CreateContainer within sandbox \"99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce\"" May 14 18:14:09.671750 containerd[1519]: time="2025-05-14T18:14:09.671731211Z" level=info msg="StartContainer for \"9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce\"" May 14 18:14:09.672535 containerd[1519]: time="2025-05-14T18:14:09.672504924Z" level=info msg="connecting to shim 9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce" address="unix:///run/containerd/s/7e165ddf942b1b24482a46ef94e146c4058ea54bd8356b6b4476bdfe234487be" protocol=ttrpc version=3 May 14 18:14:09.694867 systemd[1]: Started cri-containerd-9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce.scope - libcontainer container 9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce. May 14 18:14:09.727433 containerd[1519]: time="2025-05-14T18:14:09.727398018Z" level=info msg="StartContainer for \"9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce\" returns successfully" May 14 18:14:09.733048 systemd[1]: cri-containerd-9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce.scope: Deactivated successfully. May 14 18:14:09.733485 containerd[1519]: time="2025-05-14T18:14:09.733448932Z" level=info msg="received exit event container_id:\"9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce\" id:\"9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce\" pid:2217 exited_at:{seconds:1747246449 nanos:733282232}" May 14 18:14:09.734212 containerd[1519]: time="2025-05-14T18:14:09.734166304Z" level=info msg="TaskExit event in podsandbox handler container_id:\"9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce\" id:\"9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce\" pid:2217 exited_at:{seconds:1747246449 nanos:733282232}" May 14 18:14:09.805131 kubelet[1811]: E0514 18:14:09.805088 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:09.806414 kubelet[1811]: I0514 18:14:09.806389 1811 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 14 18:14:09.947954 containerd[1519]: time="2025-05-14T18:14:09.947858669Z" level=info msg="CreateContainer within sandbox \"99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 14 18:14:09.955321 containerd[1519]: time="2025-05-14T18:14:09.954758577Z" level=info msg="Container 17ac80187a953c5b254f9b5895265444a25691091e5ef9a81787aa26a1ca443b: CDI devices from CRI Config.CDIDevices: []" May 14 18:14:09.961255 containerd[1519]: time="2025-05-14T18:14:09.961216169Z" level=info msg="CreateContainer within sandbox \"99a78eebbe8f76737f203c29e46bea2f2d195d813d628393c80e5dc1ce486468\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"17ac80187a953c5b254f9b5895265444a25691091e5ef9a81787aa26a1ca443b\"" May 14 18:14:09.961870 containerd[1519]: time="2025-05-14T18:14:09.961829189Z" level=info msg="StartContainer for \"17ac80187a953c5b254f9b5895265444a25691091e5ef9a81787aa26a1ca443b\"" May 14 18:14:09.962625 containerd[1519]: time="2025-05-14T18:14:09.962596735Z" level=info msg="connecting to shim 17ac80187a953c5b254f9b5895265444a25691091e5ef9a81787aa26a1ca443b" address="unix:///run/containerd/s/7e165ddf942b1b24482a46ef94e146c4058ea54bd8356b6b4476bdfe234487be" protocol=ttrpc version=3 May 14 18:14:09.984821 systemd[1]: Started cri-containerd-17ac80187a953c5b254f9b5895265444a25691091e5ef9a81787aa26a1ca443b.scope - libcontainer container 17ac80187a953c5b254f9b5895265444a25691091e5ef9a81787aa26a1ca443b. May 14 18:14:10.009233 containerd[1519]: time="2025-05-14T18:14:10.009198312Z" level=info msg="StartContainer for \"17ac80187a953c5b254f9b5895265444a25691091e5ef9a81787aa26a1ca443b\" returns successfully" May 14 18:14:10.128710 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9d53f4de637bea01c3b44b1e008d34d72c8331c2baef01b7cf94bb47552936ce-rootfs.mount: Deactivated successfully. May 14 18:14:10.805853 kubelet[1811]: E0514 18:14:10.805816 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:10.962313 kubelet[1811]: I0514 18:14:10.962246 1811 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-k4m2q" podStartSLOduration=4.564024803 podStartE2EDuration="9.962230791s" podCreationTimestamp="2025-05-14 18:14:01 +0000 UTC" firstStartedPulling="2025-05-14 18:14:04.260459317 +0000 UTC m=+4.424315274" lastFinishedPulling="2025-05-14 18:14:09.658665305 +0000 UTC m=+9.822521262" observedRunningTime="2025-05-14 18:14:10.961820805 +0000 UTC m=+11.125676762" watchObservedRunningTime="2025-05-14 18:14:10.962230791 +0000 UTC m=+11.126086748" May 14 18:14:11.097668 systemd-networkd[1438]: flannel.1: Link UP May 14 18:14:11.097676 systemd-networkd[1438]: flannel.1: Gained carrier May 14 18:14:11.805981 kubelet[1811]: E0514 18:14:11.805935 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:12.197833 systemd-networkd[1438]: flannel.1: Gained IPv6LL May 14 18:14:12.249066 systemd[1]: Created slice kubepods-besteffort-pod72a8c289_c6ee_4064_832f_1373fc4c2b8d.slice - libcontainer container kubepods-besteffort-pod72a8c289_c6ee_4064_832f_1373fc4c2b8d.slice. May 14 18:14:12.285289 kubelet[1811]: I0514 18:14:12.285242 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2ggkb\" (UniqueName: \"kubernetes.io/projected/72a8c289-c6ee-4064-832f-1373fc4c2b8d-kube-api-access-2ggkb\") pod \"nginx-deployment-8587fbcb89-g2qjk\" (UID: \"72a8c289-c6ee-4064-832f-1373fc4c2b8d\") " pod="default/nginx-deployment-8587fbcb89-g2qjk" May 14 18:14:12.552491 containerd[1519]: time="2025-05-14T18:14:12.552386126Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-g2qjk,Uid:72a8c289-c6ee-4064-832f-1373fc4c2b8d,Namespace:default,Attempt:0,}" May 14 18:14:12.580030 systemd-networkd[1438]: cni0: Link UP May 14 18:14:12.586682 systemd-networkd[1438]: veth636dfeae: Link UP May 14 18:14:12.588268 kernel: cni0: port 1(veth636dfeae) entered blocking state May 14 18:14:12.588324 kernel: cni0: port 1(veth636dfeae) entered disabled state May 14 18:14:12.588340 kernel: veth636dfeae: entered allmulticast mode May 14 18:14:12.590510 kernel: veth636dfeae: entered promiscuous mode May 14 18:14:12.603565 systemd-networkd[1438]: veth636dfeae: Gained carrier May 14 18:14:12.604143 kernel: cni0: port 1(veth636dfeae) entered blocking state May 14 18:14:12.604183 kernel: cni0: port 1(veth636dfeae) entered forwarding state May 14 18:14:12.604042 systemd-networkd[1438]: cni0: Gained carrier May 14 18:14:12.605227 containerd[1519]: 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 14 18:14:12.605227 containerd[1519]: delegateAdd: netconf sent to delegate plugin: May 14 18:14:12.628456 containerd[1519]: {"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-14T18:14:12.628355213Z" level=info msg="connecting to shim 8747a9e83daca0884d2a17a3f6f708b34c9fb5327bddbcf1d5bca7462a61f062" address="unix:///run/containerd/s/29ad3f67541c4d404b70dfaea5d02eb47cd17c6078ad982c683be3e5257689d5" namespace=k8s.io protocol=ttrpc version=3 May 14 18:14:12.649787 systemd[1]: Started cri-containerd-8747a9e83daca0884d2a17a3f6f708b34c9fb5327bddbcf1d5bca7462a61f062.scope - libcontainer container 8747a9e83daca0884d2a17a3f6f708b34c9fb5327bddbcf1d5bca7462a61f062. May 14 18:14:12.660174 systemd-resolved[1354]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 14 18:14:12.677790 containerd[1519]: time="2025-05-14T18:14:12.677753852Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-g2qjk,Uid:72a8c289-c6ee-4064-832f-1373fc4c2b8d,Namespace:default,Attempt:0,} returns sandbox id \"8747a9e83daca0884d2a17a3f6f708b34c9fb5327bddbcf1d5bca7462a61f062\"" May 14 18:14:12.679371 containerd[1519]: time="2025-05-14T18:14:12.679344199Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 14 18:14:12.806174 kubelet[1811]: E0514 18:14:12.806061 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:13.807065 kubelet[1811]: E0514 18:14:13.807026 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:13.989829 systemd-networkd[1438]: veth636dfeae: Gained IPv6LL May 14 18:14:14.181894 systemd-networkd[1438]: cni0: Gained IPv6LL May 14 18:14:14.419859 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3771995715.mount: Deactivated successfully. May 14 18:14:14.807403 kubelet[1811]: E0514 18:14:14.807362 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:15.198736 containerd[1519]: time="2025-05-14T18:14:15.198618487Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:15.200002 containerd[1519]: time="2025-05-14T18:14:15.199939525Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948859" May 14 18:14:15.200592 containerd[1519]: time="2025-05-14T18:14:15.200550541Z" level=info msg="ImageCreate event name:\"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:15.203290 containerd[1519]: time="2025-05-14T18:14:15.203262092Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:15.204147 containerd[1519]: time="2025-05-14T18:14:15.204106660Z" 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.5247306s" May 14 18:14:15.204147 containerd[1519]: time="2025-05-14T18:14:15.204146839Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 14 18:14:15.208229 containerd[1519]: time="2025-05-14T18:14:15.207329138Z" level=info msg="CreateContainer within sandbox \"8747a9e83daca0884d2a17a3f6f708b34c9fb5327bddbcf1d5bca7462a61f062\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 14 18:14:15.214186 containerd[1519]: time="2025-05-14T18:14:15.214158239Z" level=info msg="Container e874c3e1918848805e5259ad8e6dda02cbdf8649df68aaf86da871e08b50e4ca: CDI devices from CRI Config.CDIDevices: []" May 14 18:14:15.216900 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount315314827.mount: Deactivated successfully. May 14 18:14:15.219080 containerd[1519]: time="2025-05-14T18:14:15.219046802Z" level=info msg="CreateContainer within sandbox \"8747a9e83daca0884d2a17a3f6f708b34c9fb5327bddbcf1d5bca7462a61f062\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"e874c3e1918848805e5259ad8e6dda02cbdf8649df68aaf86da871e08b50e4ca\"" May 14 18:14:15.219861 containerd[1519]: time="2025-05-14T18:14:15.219836504Z" level=info msg="StartContainer for \"e874c3e1918848805e5259ad8e6dda02cbdf8649df68aaf86da871e08b50e4ca\"" May 14 18:14:15.220804 containerd[1519]: time="2025-05-14T18:14:15.220769635Z" level=info msg="connecting to shim e874c3e1918848805e5259ad8e6dda02cbdf8649df68aaf86da871e08b50e4ca" address="unix:///run/containerd/s/29ad3f67541c4d404b70dfaea5d02eb47cd17c6078ad982c683be3e5257689d5" protocol=ttrpc version=3 May 14 18:14:15.246801 systemd[1]: Started cri-containerd-e874c3e1918848805e5259ad8e6dda02cbdf8649df68aaf86da871e08b50e4ca.scope - libcontainer container e874c3e1918848805e5259ad8e6dda02cbdf8649df68aaf86da871e08b50e4ca. May 14 18:14:15.277011 containerd[1519]: time="2025-05-14T18:14:15.276976527Z" level=info msg="StartContainer for \"e874c3e1918848805e5259ad8e6dda02cbdf8649df68aaf86da871e08b50e4ca\" returns successfully" May 14 18:14:15.807861 kubelet[1811]: E0514 18:14:15.807817 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:16.808815 kubelet[1811]: E0514 18:14:16.808771 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:17.809170 kubelet[1811]: E0514 18:14:17.809112 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:18.809291 kubelet[1811]: E0514 18:14:18.809256 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:19.416556 kubelet[1811]: I0514 18:14:19.416494 1811 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-g2qjk" podStartSLOduration=4.890191312 podStartE2EDuration="7.416475669s" podCreationTimestamp="2025-05-14 18:14:12 +0000 UTC" firstStartedPulling="2025-05-14 18:14:12.679006956 +0000 UTC m=+12.842862913" lastFinishedPulling="2025-05-14 18:14:15.205291313 +0000 UTC m=+15.369147270" observedRunningTime="2025-05-14 18:14:15.971624778 +0000 UTC m=+16.135480735" watchObservedRunningTime="2025-05-14 18:14:19.416475669 +0000 UTC m=+19.580331626" May 14 18:14:19.423060 systemd[1]: Created slice kubepods-besteffort-podb4cfbf64_a6b6_4071_8d8e_00e3d7a38074.slice - libcontainer container kubepods-besteffort-podb4cfbf64_a6b6_4071_8d8e_00e3d7a38074.slice. May 14 18:14:19.524264 kubelet[1811]: I0514 18:14:19.524214 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-d64lq\" (UniqueName: \"kubernetes.io/projected/b4cfbf64-a6b6-4071-8d8e-00e3d7a38074-kube-api-access-d64lq\") pod \"nfs-server-provisioner-0\" (UID: \"b4cfbf64-a6b6-4071-8d8e-00e3d7a38074\") " pod="default/nfs-server-provisioner-0" May 14 18:14:19.524264 kubelet[1811]: I0514 18:14:19.524260 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/b4cfbf64-a6b6-4071-8d8e-00e3d7a38074-data\") pod \"nfs-server-provisioner-0\" (UID: \"b4cfbf64-a6b6-4071-8d8e-00e3d7a38074\") " pod="default/nfs-server-provisioner-0" May 14 18:14:19.727582 containerd[1519]: time="2025-05-14T18:14:19.727397714Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b4cfbf64-a6b6-4071-8d8e-00e3d7a38074,Namespace:default,Attempt:0,}" May 14 18:14:19.735290 systemd-networkd[1438]: veth07b399de: Link UP May 14 18:14:19.737041 kernel: cni0: port 2(veth07b399de) entered blocking state May 14 18:14:19.737120 kernel: cni0: port 2(veth07b399de) entered disabled state May 14 18:14:19.737139 kernel: veth07b399de: entered allmulticast mode May 14 18:14:19.739317 kernel: veth07b399de: entered promiscuous mode May 14 18:14:19.743029 kernel: cni0: port 2(veth07b399de) entered blocking state May 14 18:14:19.743108 kernel: cni0: port 2(veth07b399de) entered forwarding state May 14 18:14:19.743326 systemd-networkd[1438]: veth07b399de: Gained carrier May 14 18:14:19.746324 containerd[1519]: 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)(0x400011e8e8), "name":"cbr0", "type":"bridge"} May 14 18:14:19.746324 containerd[1519]: delegateAdd: netconf sent to delegate plugin: May 14 18:14:19.764910 containerd[1519]: {"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-14T18:14:19.764869335Z" level=info msg="connecting to shim 8a3aae7e98a91e76d39762b5d48b674f0d8d103166746f85061c54d023a49df0" address="unix:///run/containerd/s/586e6aca31cb2da9c83ee0eae9fda81d8a6e40119bbf20f08448bc54c7125781" namespace=k8s.io protocol=ttrpc version=3 May 14 18:14:19.797830 systemd[1]: Started cri-containerd-8a3aae7e98a91e76d39762b5d48b674f0d8d103166746f85061c54d023a49df0.scope - libcontainer container 8a3aae7e98a91e76d39762b5d48b674f0d8d103166746f85061c54d023a49df0. May 14 18:14:19.807861 systemd-resolved[1354]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 14 18:14:19.809964 kubelet[1811]: E0514 18:14:19.809909 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:19.829004 containerd[1519]: time="2025-05-14T18:14:19.828952458Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b4cfbf64-a6b6-4071-8d8e-00e3d7a38074,Namespace:default,Attempt:0,} returns sandbox id \"8a3aae7e98a91e76d39762b5d48b674f0d8d103166746f85061c54d023a49df0\"" May 14 18:14:19.830326 containerd[1519]: time="2025-05-14T18:14:19.830302441Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 14 18:14:20.801535 kubelet[1811]: E0514 18:14:20.801491 1811 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:20.810501 kubelet[1811]: E0514 18:14:20.810484 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:21.541822 systemd-networkd[1438]: veth07b399de: Gained IPv6LL May 14 18:14:21.795986 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2149552988.mount: Deactivated successfully. May 14 18:14:21.810763 kubelet[1811]: E0514 18:14:21.810717 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:22.810992 kubelet[1811]: E0514 18:14:22.810927 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:23.044496 containerd[1519]: time="2025-05-14T18:14:23.044445675Z" 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 14 18:14:23.045585 containerd[1519]: time="2025-05-14T18:14:23.045540897Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" May 14 18:14:23.046769 containerd[1519]: time="2025-05-14T18:14:23.046714692Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:23.049183 containerd[1519]: time="2025-05-14T18:14:23.049146376Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:23.050188 containerd[1519]: time="2025-05-14T18:14:23.050155504Z" 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.219823455s" May 14 18:14:23.050301 containerd[1519]: time="2025-05-14T18:14:23.050271363Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 14 18:14:23.052430 containerd[1519]: time="2025-05-14T18:14:23.052401117Z" level=info msg="CreateContainer within sandbox \"8a3aae7e98a91e76d39762b5d48b674f0d8d103166746f85061c54d023a49df0\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 14 18:14:23.059677 containerd[1519]: time="2025-05-14T18:14:23.058489568Z" level=info msg="Container 0b29252e201cfede59b8a0e2340ecda874e12867b786b89a6bfaae047f58ba14: CDI devices from CRI Config.CDIDevices: []" May 14 18:14:23.071340 containerd[1519]: time="2025-05-14T18:14:23.071125988Z" level=info msg="CreateContainer within sandbox \"8a3aae7e98a91e76d39762b5d48b674f0d8d103166746f85061c54d023a49df0\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"0b29252e201cfede59b8a0e2340ecda874e12867b786b89a6bfaae047f58ba14\"" May 14 18:14:23.072381 containerd[1519]: time="2025-05-14T18:14:23.072170322Z" level=info msg="StartContainer for \"0b29252e201cfede59b8a0e2340ecda874e12867b786b89a6bfaae047f58ba14\"" May 14 18:14:23.073091 containerd[1519]: time="2025-05-14T18:14:23.073066951Z" level=info msg="connecting to shim 0b29252e201cfede59b8a0e2340ecda874e12867b786b89a6bfaae047f58ba14" address="unix:///run/containerd/s/586e6aca31cb2da9c83ee0eae9fda81d8a6e40119bbf20f08448bc54c7125781" protocol=ttrpc version=3 May 14 18:14:23.099811 systemd[1]: Started cri-containerd-0b29252e201cfede59b8a0e2340ecda874e12867b786b89a6bfaae047f58ba14.scope - libcontainer container 0b29252e201cfede59b8a0e2340ecda874e12867b786b89a6bfaae047f58ba14. May 14 18:14:23.129231 containerd[1519]: time="2025-05-14T18:14:23.129190036Z" level=info msg="StartContainer for \"0b29252e201cfede59b8a0e2340ecda874e12867b786b89a6bfaae047f58ba14\" returns successfully" May 14 18:14:23.811609 kubelet[1811]: E0514 18:14:23.811572 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:24.812129 kubelet[1811]: E0514 18:14:24.812079 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:25.812874 kubelet[1811]: E0514 18:14:25.812832 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:26.813337 kubelet[1811]: E0514 18:14:26.813286 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:27.814366 kubelet[1811]: E0514 18:14:27.814320 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:28.815407 kubelet[1811]: E0514 18:14:28.815358 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:29.816155 kubelet[1811]: E0514 18:14:29.816105 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:30.817198 kubelet[1811]: E0514 18:14:30.817156 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:31.817817 kubelet[1811]: E0514 18:14:31.817773 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:32.818631 kubelet[1811]: E0514 18:14:32.818553 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:33.210315 kubelet[1811]: I0514 18:14:33.210258 1811 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.989218621 podStartE2EDuration="14.210242781s" podCreationTimestamp="2025-05-14 18:14:19 +0000 UTC" firstStartedPulling="2025-05-14 18:14:19.830113427 +0000 UTC m=+19.993969384" lastFinishedPulling="2025-05-14 18:14:23.051137587 +0000 UTC m=+23.214993544" observedRunningTime="2025-05-14 18:14:23.990210655 +0000 UTC m=+24.154066612" watchObservedRunningTime="2025-05-14 18:14:33.210242781 +0000 UTC m=+33.374098738" May 14 18:14:33.216328 systemd[1]: Created slice kubepods-besteffort-pod4c2d4749_daeb_4275_b866_3ac8dec5233c.slice - libcontainer container kubepods-besteffort-pod4c2d4749_daeb_4275_b866_3ac8dec5233c.slice. May 14 18:14:33.301976 kubelet[1811]: I0514 18:14:33.301933 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c6bcd97d-a975-4363-b85a-db3b1045cb5b\" (UniqueName: \"kubernetes.io/nfs/4c2d4749-daeb-4275-b866-3ac8dec5233c-pvc-c6bcd97d-a975-4363-b85a-db3b1045cb5b\") pod \"test-pod-1\" (UID: \"4c2d4749-daeb-4275-b866-3ac8dec5233c\") " pod="default/test-pod-1" May 14 18:14:33.302154 kubelet[1811]: I0514 18:14:33.302140 1811 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rvk7t\" (UniqueName: \"kubernetes.io/projected/4c2d4749-daeb-4275-b866-3ac8dec5233c-kube-api-access-rvk7t\") pod \"test-pod-1\" (UID: \"4c2d4749-daeb-4275-b866-3ac8dec5233c\") " pod="default/test-pod-1" May 14 18:14:33.426677 kernel: netfs: FS-Cache loaded May 14 18:14:33.461078 kernel: RPC: Registered named UNIX socket transport module. May 14 18:14:33.461168 kernel: RPC: Registered udp transport module. May 14 18:14:33.461184 kernel: RPC: Registered tcp transport module. May 14 18:14:33.463040 kernel: RPC: Registered tcp-with-tls transport module. May 14 18:14:33.463070 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 14 18:14:33.645773 kernel: NFS: Registering the id_resolver key type May 14 18:14:33.645901 kernel: Key type id_resolver registered May 14 18:14:33.645919 kernel: Key type id_legacy registered May 14 18:14:33.674892 nfsidmap[2816]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf May 14 18:14:33.675891 nfsidmap[2816]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 14 18:14:33.678853 nfsidmap[2819]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf May 14 18:14:33.679001 nfsidmap[2819]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 14 18:14:33.686497 nfsrahead[2823]: setting /var/lib/kubelet/pods/4c2d4749-daeb-4275-b866-3ac8dec5233c/volumes/kubernetes.io~nfs/pvc-c6bcd97d-a975-4363-b85a-db3b1045cb5b readahead to 128 May 14 18:14:33.819102 kubelet[1811]: E0514 18:14:33.819000 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:33.820026 containerd[1519]: time="2025-05-14T18:14:33.819968911Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4c2d4749-daeb-4275-b866-3ac8dec5233c,Namespace:default,Attempt:0,}" May 14 18:14:33.834699 systemd-networkd[1438]: veth40714dba: Link UP May 14 18:14:33.836196 kernel: cni0: port 3(veth40714dba) entered blocking state May 14 18:14:33.836257 kernel: cni0: port 3(veth40714dba) entered disabled state May 14 18:14:33.836992 kernel: veth40714dba: entered allmulticast mode May 14 18:14:33.837853 kernel: veth40714dba: entered promiscuous mode May 14 18:14:33.846438 kernel: cni0: port 3(veth40714dba) entered blocking state May 14 18:14:33.846483 kernel: cni0: port 3(veth40714dba) entered forwarding state May 14 18:14:33.846249 systemd-networkd[1438]: veth40714dba: Gained carrier May 14 18:14:33.848076 containerd[1519]: 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)(0x4000012678), "name":"cbr0", "type":"bridge"} May 14 18:14:33.848076 containerd[1519]: delegateAdd: netconf sent to delegate plugin: May 14 18:14:33.872207 containerd[1519]: {"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-14T18:14:33.872170581Z" level=info msg="connecting to shim 6014a228ea8c34c989a283d6c4ea1381663d30393e312486b35973d993851855" address="unix:///run/containerd/s/5cbd7547c9433b6e575bcd68722ba2c1bd876b2ae2bb3c5ad33b0bff38a0396f" namespace=k8s.io protocol=ttrpc version=3 May 14 18:14:33.894815 systemd[1]: Started cri-containerd-6014a228ea8c34c989a283d6c4ea1381663d30393e312486b35973d993851855.scope - libcontainer container 6014a228ea8c34c989a283d6c4ea1381663d30393e312486b35973d993851855. May 14 18:14:33.908172 systemd-resolved[1354]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 14 18:14:33.929371 containerd[1519]: time="2025-05-14T18:14:33.929308595Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4c2d4749-daeb-4275-b866-3ac8dec5233c,Namespace:default,Attempt:0,} returns sandbox id \"6014a228ea8c34c989a283d6c4ea1381663d30393e312486b35973d993851855\"" May 14 18:14:33.933076 containerd[1519]: time="2025-05-14T18:14:33.933045526Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 14 18:14:34.304227 containerd[1519]: time="2025-05-14T18:14:34.303416931Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 18:14:34.304227 containerd[1519]: time="2025-05-14T18:14:34.304111942Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 14 18:14:34.306925 containerd[1519]: time="2025-05-14T18:14:34.306804662Z" 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 373.618045ms" May 14 18:14:34.306925 containerd[1519]: time="2025-05-14T18:14:34.306842664Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 14 18:14:34.309756 containerd[1519]: time="2025-05-14T18:14:34.308877655Z" level=info msg="CreateContainer within sandbox \"6014a228ea8c34c989a283d6c4ea1381663d30393e312486b35973d993851855\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 14 18:14:34.320165 containerd[1519]: time="2025-05-14T18:14:34.319235701Z" level=info msg="Container 7f1aed5e14ef4a489c6ee32987e14b950250fafdfb2242098543e516c34f1dfb: CDI devices from CRI Config.CDIDevices: []" May 14 18:14:34.326170 containerd[1519]: time="2025-05-14T18:14:34.326126491Z" level=info msg="CreateContainer within sandbox \"6014a228ea8c34c989a283d6c4ea1381663d30393e312486b35973d993851855\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"7f1aed5e14ef4a489c6ee32987e14b950250fafdfb2242098543e516c34f1dfb\"" May 14 18:14:34.328029 containerd[1519]: time="2025-05-14T18:14:34.326741496Z" level=info msg="StartContainer for \"7f1aed5e14ef4a489c6ee32987e14b950250fafdfb2242098543e516c34f1dfb\"" May 14 18:14:34.328029 containerd[1519]: time="2025-05-14T18:14:34.327559717Z" level=info msg="connecting to shim 7f1aed5e14ef4a489c6ee32987e14b950250fafdfb2242098543e516c34f1dfb" address="unix:///run/containerd/s/5cbd7547c9433b6e575bcd68722ba2c1bd876b2ae2bb3c5ad33b0bff38a0396f" protocol=ttrpc version=3 May 14 18:14:34.349841 systemd[1]: Started cri-containerd-7f1aed5e14ef4a489c6ee32987e14b950250fafdfb2242098543e516c34f1dfb.scope - libcontainer container 7f1aed5e14ef4a489c6ee32987e14b950250fafdfb2242098543e516c34f1dfb. May 14 18:14:34.386785 containerd[1519]: time="2025-05-14T18:14:34.386750215Z" level=info msg="StartContainer for \"7f1aed5e14ef4a489c6ee32987e14b950250fafdfb2242098543e516c34f1dfb\" returns successfully" May 14 18:14:34.819484 kubelet[1811]: E0514 18:14:34.819424 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:35.820612 kubelet[1811]: E0514 18:14:35.820569 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 18:14:35.877824 systemd-networkd[1438]: veth40714dba: Gained IPv6LL May 14 18:14:36.408838 update_engine[1511]: I20250514 18:14:36.408732 1511 update_attempter.cc:509] Updating boot flags... May 14 18:14:36.821367 kubelet[1811]: E0514 18:14:36.821253 1811 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"