Oct 27 23:28:06.801721 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 27 23:28:06.801741 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT Mon Oct 27 22:06:39 -00 2025 Oct 27 23:28:06.801751 kernel: KASLR enabled Oct 27 23:28:06.801757 kernel: efi: EFI v2.7 by EDK II Oct 27 23:28:06.801762 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Oct 27 23:28:06.801767 kernel: random: crng init done Oct 27 23:28:06.801774 kernel: secureboot: Secure boot disabled Oct 27 23:28:06.801780 kernel: ACPI: Early table checksum verification disabled Oct 27 23:28:06.801786 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 27 23:28:06.801801 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 27 23:28:06.801807 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801813 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801818 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801824 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801831 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801839 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801845 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801851 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801857 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 27 23:28:06.801863 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 27 23:28:06.801869 kernel: ACPI: Use ACPI SPCR as default console: No Oct 27 23:28:06.801875 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 23:28:06.801881 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Oct 27 23:28:06.801887 kernel: Zone ranges: Oct 27 23:28:06.801893 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 23:28:06.801901 kernel: DMA32 empty Oct 27 23:28:06.801907 kernel: Normal empty Oct 27 23:28:06.801913 kernel: Device empty Oct 27 23:28:06.801918 kernel: Movable zone start for each node Oct 27 23:28:06.801924 kernel: Early memory node ranges Oct 27 23:28:06.801930 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Oct 27 23:28:06.801936 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Oct 27 23:28:06.801942 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Oct 27 23:28:06.801948 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 27 23:28:06.801954 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 27 23:28:06.801960 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 27 23:28:06.801966 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 27 23:28:06.801973 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 27 23:28:06.801979 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 27 23:28:06.801986 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 27 23:28:06.801994 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 27 23:28:06.802000 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 27 23:28:06.802007 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 27 23:28:06.802014 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 27 23:28:06.802021 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 27 23:28:06.802027 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Oct 27 23:28:06.802033 kernel: psci: probing for conduit method from ACPI. Oct 27 23:28:06.802040 kernel: psci: PSCIv1.1 detected in firmware. Oct 27 23:28:06.802046 kernel: psci: Using standard PSCI v0.2 function IDs Oct 27 23:28:06.802052 kernel: psci: Trusted OS migration not required Oct 27 23:28:06.802058 kernel: psci: SMC Calling Convention v1.1 Oct 27 23:28:06.802065 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 27 23:28:06.802071 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 27 23:28:06.802079 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 27 23:28:06.802086 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 27 23:28:06.802092 kernel: Detected PIPT I-cache on CPU0 Oct 27 23:28:06.802099 kernel: CPU features: detected: GIC system register CPU interface Oct 27 23:28:06.802105 kernel: CPU features: detected: Spectre-v4 Oct 27 23:28:06.802111 kernel: CPU features: detected: Spectre-BHB Oct 27 23:28:06.802117 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 27 23:28:06.802124 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 27 23:28:06.802130 kernel: CPU features: detected: ARM erratum 1418040 Oct 27 23:28:06.802136 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 27 23:28:06.802143 kernel: alternatives: applying boot alternatives Oct 27 23:28:06.802150 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=7da44627248fe1fbee2c83c4ccd30b78ae5d30059ff898a840de6b6417372b60 Oct 27 23:28:06.802158 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 27 23:28:06.802164 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 27 23:28:06.802171 kernel: Fallback order for Node 0: 0 Oct 27 23:28:06.802177 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 27 23:28:06.802183 kernel: Policy zone: DMA Oct 27 23:28:06.802190 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 27 23:28:06.802196 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 27 23:28:06.802202 kernel: software IO TLB: area num 4. Oct 27 23:28:06.802208 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 27 23:28:06.802215 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Oct 27 23:28:06.802221 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 27 23:28:06.802229 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 27 23:28:06.802236 kernel: rcu: RCU event tracing is enabled. Oct 27 23:28:06.802242 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 27 23:28:06.802249 kernel: Trampoline variant of Tasks RCU enabled. Oct 27 23:28:06.802256 kernel: Tracing variant of Tasks RCU enabled. Oct 27 23:28:06.802262 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 27 23:28:06.802269 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 27 23:28:06.802275 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 27 23:28:06.802282 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 27 23:28:06.802288 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 27 23:28:06.802295 kernel: GICv3: 256 SPIs implemented Oct 27 23:28:06.802302 kernel: GICv3: 0 Extended SPIs implemented Oct 27 23:28:06.802308 kernel: Root IRQ handler: gic_handle_irq Oct 27 23:28:06.802315 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 27 23:28:06.802321 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 27 23:28:06.802328 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 27 23:28:06.802334 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 27 23:28:06.802341 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 27 23:28:06.802347 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 27 23:28:06.802354 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 27 23:28:06.802361 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 27 23:28:06.802367 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 27 23:28:06.802374 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:28:06.802382 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 27 23:28:06.802389 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 27 23:28:06.802395 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 27 23:28:06.802410 kernel: arm-pv: using stolen time PV Oct 27 23:28:06.802417 kernel: Console: colour dummy device 80x25 Oct 27 23:28:06.802424 kernel: ACPI: Core revision 20240827 Oct 27 23:28:06.802431 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 27 23:28:06.802438 kernel: pid_max: default: 32768 minimum: 301 Oct 27 23:28:06.802518 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 27 23:28:06.802527 kernel: landlock: Up and running. Oct 27 23:28:06.802538 kernel: SELinux: Initializing. Oct 27 23:28:06.802544 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 23:28:06.802552 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 27 23:28:06.802558 kernel: rcu: Hierarchical SRCU implementation. Oct 27 23:28:06.802566 kernel: rcu: Max phase no-delay instances is 400. Oct 27 23:28:06.802573 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 27 23:28:06.802580 kernel: Remapping and enabling EFI services. Oct 27 23:28:06.802586 kernel: smp: Bringing up secondary CPUs ... Oct 27 23:28:06.802593 kernel: Detected PIPT I-cache on CPU1 Oct 27 23:28:06.802607 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 27 23:28:06.802614 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 27 23:28:06.802622 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:28:06.802630 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 27 23:28:06.802651 kernel: Detected PIPT I-cache on CPU2 Oct 27 23:28:06.802659 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 27 23:28:06.802666 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 27 23:28:06.802673 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:28:06.802681 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 27 23:28:06.802688 kernel: Detected PIPT I-cache on CPU3 Oct 27 23:28:06.802696 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 27 23:28:06.802703 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 27 23:28:06.802710 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 27 23:28:06.802717 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 27 23:28:06.802724 kernel: smp: Brought up 1 node, 4 CPUs Oct 27 23:28:06.802730 kernel: SMP: Total of 4 processors activated. Oct 27 23:28:06.802737 kernel: CPU: All CPU(s) started at EL1 Oct 27 23:28:06.802746 kernel: CPU features: detected: 32-bit EL0 Support Oct 27 23:28:06.802753 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 27 23:28:06.802760 kernel: CPU features: detected: Common not Private translations Oct 27 23:28:06.802767 kernel: CPU features: detected: CRC32 instructions Oct 27 23:28:06.802774 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 27 23:28:06.802781 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 27 23:28:06.802788 kernel: CPU features: detected: LSE atomic instructions Oct 27 23:28:06.802802 kernel: CPU features: detected: Privileged Access Never Oct 27 23:28:06.802810 kernel: CPU features: detected: RAS Extension Support Oct 27 23:28:06.802819 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 27 23:28:06.802826 kernel: alternatives: applying system-wide alternatives Oct 27 23:28:06.802834 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 27 23:28:06.802842 kernel: Memory: 2424416K/2572288K available (11136K kernel code, 2450K rwdata, 9076K rodata, 38976K init, 1038K bss, 125536K reserved, 16384K cma-reserved) Oct 27 23:28:06.802849 kernel: devtmpfs: initialized Oct 27 23:28:06.802856 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 27 23:28:06.802864 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 27 23:28:06.802871 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 27 23:28:06.802878 kernel: 0 pages in range for non-PLT usage Oct 27 23:28:06.802887 kernel: 508560 pages in range for PLT usage Oct 27 23:28:06.802894 kernel: pinctrl core: initialized pinctrl subsystem Oct 27 23:28:06.802901 kernel: SMBIOS 3.0.0 present. Oct 27 23:28:06.802908 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 27 23:28:06.802914 kernel: DMI: Memory slots populated: 1/1 Oct 27 23:28:06.802921 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 27 23:28:06.802928 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 27 23:28:06.802935 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 27 23:28:06.802942 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 27 23:28:06.802951 kernel: audit: initializing netlink subsys (disabled) Oct 27 23:28:06.802958 kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Oct 27 23:28:06.802965 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 27 23:28:06.802971 kernel: cpuidle: using governor menu Oct 27 23:28:06.802979 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 27 23:28:06.802986 kernel: ASID allocator initialised with 32768 entries Oct 27 23:28:06.802993 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 27 23:28:06.802999 kernel: Serial: AMBA PL011 UART driver Oct 27 23:28:06.803006 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 27 23:28:06.803014 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 27 23:28:06.803021 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 27 23:28:06.803028 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 27 23:28:06.803035 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 27 23:28:06.803042 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 27 23:28:06.803049 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 27 23:28:06.803056 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 27 23:28:06.803062 kernel: ACPI: Added _OSI(Module Device) Oct 27 23:28:06.803069 kernel: ACPI: Added _OSI(Processor Device) Oct 27 23:28:06.803077 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 27 23:28:06.803084 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 27 23:28:06.803091 kernel: ACPI: Interpreter enabled Oct 27 23:28:06.803098 kernel: ACPI: Using GIC for interrupt routing Oct 27 23:28:06.803105 kernel: ACPI: MCFG table detected, 1 entries Oct 27 23:28:06.803112 kernel: ACPI: CPU0 has been hot-added Oct 27 23:28:06.803119 kernel: ACPI: CPU1 has been hot-added Oct 27 23:28:06.803125 kernel: ACPI: CPU2 has been hot-added Oct 27 23:28:06.803132 kernel: ACPI: CPU3 has been hot-added Oct 27 23:28:06.803139 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 27 23:28:06.803147 kernel: printk: legacy console [ttyAMA0] enabled Oct 27 23:28:06.803154 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 27 23:28:06.803295 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 27 23:28:06.803361 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 27 23:28:06.803442 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 27 23:28:06.803503 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 27 23:28:06.803567 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 27 23:28:06.803581 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 27 23:28:06.803588 kernel: PCI host bridge to bus 0000:00 Oct 27 23:28:06.803667 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 27 23:28:06.803724 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 27 23:28:06.803777 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 27 23:28:06.803840 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 27 23:28:06.803918 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 27 23:28:06.803991 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 27 23:28:06.804052 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 27 23:28:06.804112 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 27 23:28:06.804171 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 27 23:28:06.804242 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 27 23:28:06.804302 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 27 23:28:06.804364 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 27 23:28:06.804442 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 27 23:28:06.804499 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 27 23:28:06.804554 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 27 23:28:06.804563 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 27 23:28:06.804571 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 27 23:28:06.804578 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 27 23:28:06.804585 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 27 23:28:06.804595 kernel: iommu: Default domain type: Translated Oct 27 23:28:06.804602 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 27 23:28:06.804609 kernel: efivars: Registered efivars operations Oct 27 23:28:06.804616 kernel: vgaarb: loaded Oct 27 23:28:06.804623 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 27 23:28:06.804630 kernel: VFS: Disk quotas dquot_6.6.0 Oct 27 23:28:06.804637 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 27 23:28:06.804643 kernel: pnp: PnP ACPI init Oct 27 23:28:06.804715 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 27 23:28:06.804727 kernel: pnp: PnP ACPI: found 1 devices Oct 27 23:28:06.804734 kernel: NET: Registered PF_INET protocol family Oct 27 23:28:06.804742 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 27 23:28:06.804749 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 27 23:28:06.804756 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 27 23:28:06.804763 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 27 23:28:06.804770 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 27 23:28:06.804777 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 27 23:28:06.804786 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 23:28:06.804800 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 27 23:28:06.804807 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 27 23:28:06.804814 kernel: PCI: CLS 0 bytes, default 64 Oct 27 23:28:06.804821 kernel: kvm [1]: HYP mode not available Oct 27 23:28:06.804828 kernel: Initialise system trusted keyrings Oct 27 23:28:06.804836 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 27 23:28:06.804843 kernel: Key type asymmetric registered Oct 27 23:28:06.804850 kernel: Asymmetric key parser 'x509' registered Oct 27 23:28:06.804859 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 27 23:28:06.804866 kernel: io scheduler mq-deadline registered Oct 27 23:28:06.804873 kernel: io scheduler kyber registered Oct 27 23:28:06.804880 kernel: io scheduler bfq registered Oct 27 23:28:06.804887 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 27 23:28:06.804894 kernel: ACPI: button: Power Button [PWRB] Oct 27 23:28:06.804901 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 27 23:28:06.804965 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 27 23:28:06.804975 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 27 23:28:06.804984 kernel: thunder_xcv, ver 1.0 Oct 27 23:28:06.804991 kernel: thunder_bgx, ver 1.0 Oct 27 23:28:06.804998 kernel: nicpf, ver 1.0 Oct 27 23:28:06.805005 kernel: nicvf, ver 1.0 Oct 27 23:28:06.805075 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 27 23:28:06.805132 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-27T23:28:06 UTC (1761607686) Oct 27 23:28:06.805142 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 27 23:28:06.805149 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 27 23:28:06.805158 kernel: watchdog: NMI not fully supported Oct 27 23:28:06.805165 kernel: watchdog: Hard watchdog permanently disabled Oct 27 23:28:06.805172 kernel: NET: Registered PF_INET6 protocol family Oct 27 23:28:06.805179 kernel: Segment Routing with IPv6 Oct 27 23:28:06.805187 kernel: In-situ OAM (IOAM) with IPv6 Oct 27 23:28:06.805193 kernel: NET: Registered PF_PACKET protocol family Oct 27 23:28:06.805200 kernel: Key type dns_resolver registered Oct 27 23:28:06.805208 kernel: registered taskstats version 1 Oct 27 23:28:06.805215 kernel: Loading compiled-in X.509 certificates Oct 27 23:28:06.805222 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: d36d3f99f7c8356b27e0c5530c216cd6f7ab4d7e' Oct 27 23:28:06.805230 kernel: Demotion targets for Node 0: null Oct 27 23:28:06.805237 kernel: Key type .fscrypt registered Oct 27 23:28:06.805244 kernel: Key type fscrypt-provisioning registered Oct 27 23:28:06.805251 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 27 23:28:06.805258 kernel: ima: Allocated hash algorithm: sha1 Oct 27 23:28:06.805265 kernel: ima: No architecture policies found Oct 27 23:28:06.805272 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 27 23:28:06.805279 kernel: clk: Disabling unused clocks Oct 27 23:28:06.805286 kernel: PM: genpd: Disabling unused power domains Oct 27 23:28:06.805294 kernel: Warning: unable to open an initial console. Oct 27 23:28:06.805301 kernel: Freeing unused kernel memory: 38976K Oct 27 23:28:06.805308 kernel: Run /init as init process Oct 27 23:28:06.805315 kernel: with arguments: Oct 27 23:28:06.805322 kernel: /init Oct 27 23:28:06.805329 kernel: with environment: Oct 27 23:28:06.805336 kernel: HOME=/ Oct 27 23:28:06.805343 kernel: TERM=linux Oct 27 23:28:06.805351 systemd[1]: Successfully made /usr/ read-only. Oct 27 23:28:06.805362 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) Oct 27 23:28:06.805371 systemd[1]: Detected virtualization kvm. Oct 27 23:28:06.805378 systemd[1]: Detected architecture arm64. Oct 27 23:28:06.805385 systemd[1]: Running in initrd. Oct 27 23:28:06.805392 systemd[1]: No hostname configured, using default hostname. Oct 27 23:28:06.805474 systemd[1]: Hostname set to . Oct 27 23:28:06.805484 systemd[1]: Initializing machine ID from VM UUID. Oct 27 23:28:06.805494 systemd[1]: Queued start job for default target initrd.target. Oct 27 23:28:06.805501 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 23:28:06.805509 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 23:28:06.805518 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 27 23:28:06.805525 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 27 23:28:06.805533 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 27 23:28:06.805541 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 27 23:28:06.805551 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 27 23:28:06.805560 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 27 23:28:06.805568 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 23:28:06.805575 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 27 23:28:06.805583 systemd[1]: Reached target paths.target - Path Units. Oct 27 23:28:06.805590 systemd[1]: Reached target slices.target - Slice Units. Oct 27 23:28:06.805598 systemd[1]: Reached target swap.target - Swaps. Oct 27 23:28:06.805606 systemd[1]: Reached target timers.target - Timer Units. Oct 27 23:28:06.805614 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 27 23:28:06.805622 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 27 23:28:06.805630 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 27 23:28:06.805637 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 27 23:28:06.805645 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 27 23:28:06.805653 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 27 23:28:06.805660 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 23:28:06.805668 systemd[1]: Reached target sockets.target - Socket Units. Oct 27 23:28:06.805675 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 27 23:28:06.805684 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 27 23:28:06.805692 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 27 23:28:06.805700 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). Oct 27 23:28:06.805708 systemd[1]: Starting systemd-fsck-usr.service... Oct 27 23:28:06.805716 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 27 23:28:06.805723 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 27 23:28:06.805731 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:28:06.805738 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 27 23:28:06.805748 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 23:28:06.805756 systemd[1]: Finished systemd-fsck-usr.service. Oct 27 23:28:06.805787 systemd-journald[246]: Collecting audit messages is disabled. Oct 27 23:28:06.805817 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 27 23:28:06.805826 systemd-journald[246]: Journal started Oct 27 23:28:06.805844 systemd-journald[246]: Runtime Journal (/run/log/journal/a1b59278d8e744a4be1c6f5bf42fcd19) is 6M, max 48.5M, 42.4M free. Oct 27 23:28:06.796565 systemd-modules-load[248]: Inserted module 'overlay' Oct 27 23:28:06.808076 systemd[1]: Started systemd-journald.service - Journal Service. Oct 27 23:28:06.811418 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 27 23:28:06.813213 systemd-modules-load[248]: Inserted module 'br_netfilter' Oct 27 23:28:06.814287 kernel: Bridge firewalling registered Oct 27 23:28:06.814530 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:28:06.816189 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 27 23:28:06.820321 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 27 23:28:06.822423 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 27 23:28:06.825138 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 27 23:28:06.830540 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 23:28:06.833255 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 27 23:28:06.842035 systemd-tmpfiles[268]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 27 23:28:06.842685 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 27 23:28:06.844392 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 23:28:06.845981 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 23:28:06.850271 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 27 23:28:06.853596 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 23:28:06.870038 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 27 23:28:06.885843 dracut-cmdline[292]: 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=7da44627248fe1fbee2c83c4ccd30b78ae5d30059ff898a840de6b6417372b60 Oct 27 23:28:06.902507 systemd-resolved[287]: Positive Trust Anchors: Oct 27 23:28:06.902526 systemd-resolved[287]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 23:28:06.902558 systemd-resolved[287]: 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 Oct 27 23:28:06.908307 systemd-resolved[287]: Defaulting to hostname 'linux'. Oct 27 23:28:06.909527 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 27 23:28:06.913964 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 27 23:28:06.966431 kernel: SCSI subsystem initialized Oct 27 23:28:06.971416 kernel: Loading iSCSI transport class v2.0-870. Oct 27 23:28:06.979441 kernel: iscsi: registered transport (tcp) Oct 27 23:28:06.991725 kernel: iscsi: registered transport (qla4xxx) Oct 27 23:28:06.991757 kernel: QLogic iSCSI HBA Driver Oct 27 23:28:07.009726 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 27 23:28:07.033242 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 27 23:28:07.036222 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 27 23:28:07.080762 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 27 23:28:07.083241 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 27 23:28:07.156446 kernel: raid6: neonx8 gen() 15345 MB/s Oct 27 23:28:07.173428 kernel: raid6: neonx4 gen() 15783 MB/s Oct 27 23:28:07.190425 kernel: raid6: neonx2 gen() 13227 MB/s Oct 27 23:28:07.207425 kernel: raid6: neonx1 gen() 10486 MB/s Oct 27 23:28:07.224427 kernel: raid6: int64x8 gen() 6897 MB/s Oct 27 23:28:07.241426 kernel: raid6: int64x4 gen() 7349 MB/s Oct 27 23:28:07.258427 kernel: raid6: int64x2 gen() 6105 MB/s Oct 27 23:28:07.275678 kernel: raid6: int64x1 gen() 5047 MB/s Oct 27 23:28:07.275706 kernel: raid6: using algorithm neonx4 gen() 15783 MB/s Oct 27 23:28:07.293616 kernel: raid6: .... xor() 12336 MB/s, rmw enabled Oct 27 23:28:07.293642 kernel: raid6: using neon recovery algorithm Oct 27 23:28:07.299603 kernel: xor: measuring software checksum speed Oct 27 23:28:07.299628 kernel: 8regs : 21624 MB/sec Oct 27 23:28:07.300427 kernel: 32regs : 21624 MB/sec Oct 27 23:28:07.301662 kernel: arm64_neon : 22459 MB/sec Oct 27 23:28:07.301677 kernel: xor: using function: arm64_neon (22459 MB/sec) Oct 27 23:28:07.353434 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 27 23:28:07.359984 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 27 23:28:07.362598 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 23:28:07.388007 systemd-udevd[500]: Using default interface naming scheme 'v255'. Oct 27 23:28:07.392041 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 23:28:07.394551 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 27 23:28:07.420971 dracut-pre-trigger[508]: rd.md=0: removing MD RAID activation Oct 27 23:28:07.445480 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 27 23:28:07.447969 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 27 23:28:07.503637 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 23:28:07.506418 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 27 23:28:07.556073 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 27 23:28:07.559949 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Oct 27 23:28:07.564222 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 27 23:28:07.564266 kernel: GPT:9289727 != 19775487 Oct 27 23:28:07.564276 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 27 23:28:07.564859 kernel: GPT:9289727 != 19775487 Oct 27 23:28:07.565612 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 27 23:28:07.566415 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 23:28:07.567635 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 23:28:07.567754 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:28:07.571534 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:28:07.574477 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:28:07.602156 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:28:07.610192 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 27 23:28:07.611849 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 27 23:28:07.620923 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 27 23:28:07.628528 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 27 23:28:07.634761 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 27 23:28:07.636084 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 27 23:28:07.638574 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 27 23:28:07.641654 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 23:28:07.643897 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 27 23:28:07.646746 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 27 23:28:07.648680 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 27 23:28:07.668363 disk-uuid[592]: Primary Header is updated. Oct 27 23:28:07.668363 disk-uuid[592]: Secondary Entries is updated. Oct 27 23:28:07.668363 disk-uuid[592]: Secondary Header is updated. Oct 27 23:28:07.673690 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 27 23:28:07.676874 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 23:28:07.681424 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 23:28:08.681432 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 27 23:28:08.682504 disk-uuid[597]: The operation has completed successfully. Oct 27 23:28:08.707590 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 27 23:28:08.707724 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 27 23:28:08.734531 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 27 23:28:08.752969 sh[615]: Success Oct 27 23:28:08.766725 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 27 23:28:08.766792 kernel: device-mapper: uevent: version 1.0.3 Oct 27 23:28:08.768060 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 27 23:28:08.775630 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 27 23:28:08.805435 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 27 23:28:08.807274 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 27 23:28:08.820155 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 27 23:28:08.829456 kernel: BTRFS: device fsid 5a6ca053-244f-4cbd-93f9-9b9e55af9b0a devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (627) Oct 27 23:28:08.832192 kernel: BTRFS info (device dm-0): first mount of filesystem 5a6ca053-244f-4cbd-93f9-9b9e55af9b0a Oct 27 23:28:08.832229 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:28:08.836778 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 27 23:28:08.836826 kernel: BTRFS info (device dm-0): enabling free space tree Oct 27 23:28:08.838103 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 27 23:28:08.839637 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 27 23:28:08.841304 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 27 23:28:08.842208 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 27 23:28:08.844040 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 27 23:28:08.870627 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (658) Oct 27 23:28:08.870662 kernel: BTRFS info (device vda6): first mount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:28:08.870673 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:28:08.874955 kernel: BTRFS info (device vda6): turning on async discard Oct 27 23:28:08.874990 kernel: BTRFS info (device vda6): enabling free space tree Oct 27 23:28:08.879497 kernel: BTRFS info (device vda6): last unmount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:28:08.879632 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 27 23:28:08.882271 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 27 23:28:08.946467 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 27 23:28:08.950453 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 27 23:28:08.996174 ignition[704]: Ignition 2.22.0 Oct 27 23:28:08.996187 ignition[704]: Stage: fetch-offline Oct 27 23:28:08.997246 systemd-networkd[806]: lo: Link UP Oct 27 23:28:08.996213 ignition[704]: no configs at "/usr/lib/ignition/base.d" Oct 27 23:28:08.997249 systemd-networkd[806]: lo: Gained carrier Oct 27 23:28:08.996221 ignition[704]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:28:08.997943 systemd-networkd[806]: Enumeration completed Oct 27 23:28:08.996301 ignition[704]: parsed url from cmdline: "" Oct 27 23:28:08.998109 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 27 23:28:08.996304 ignition[704]: no config URL provided Oct 27 23:28:08.998335 systemd-networkd[806]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:28:08.996310 ignition[704]: reading system config file "/usr/lib/ignition/user.ign" Oct 27 23:28:08.998339 systemd-networkd[806]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 27 23:28:08.996317 ignition[704]: no config at "/usr/lib/ignition/user.ign" Oct 27 23:28:08.999139 systemd-networkd[806]: eth0: Link UP Oct 27 23:28:08.996333 ignition[704]: op(1): [started] loading QEMU firmware config module Oct 27 23:28:08.999228 systemd-networkd[806]: eth0: Gained carrier Oct 27 23:28:08.996337 ignition[704]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 27 23:28:08.999236 systemd-networkd[806]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:28:09.012586 ignition[704]: op(1): [finished] loading QEMU firmware config module Oct 27 23:28:09.000147 systemd[1]: Reached target network.target - Network. Oct 27 23:28:09.019444 systemd-networkd[806]: eth0: DHCPv4 address 10.0.0.33/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 27 23:28:09.027656 ignition[704]: parsing config with SHA512: 335c3f4e714d3ed0d3f89fd37a288d047030eb0f5ad6f102673d3534c275de69d5d6df50feb4426f51f52198d24d3c9c8a49869b026bdfb803d235967bf4a698 Oct 27 23:28:09.030185 unknown[704]: fetched base config from "system" Oct 27 23:28:09.030195 unknown[704]: fetched user config from "qemu" Oct 27 23:28:09.030447 ignition[704]: fetch-offline: fetch-offline passed Oct 27 23:28:09.030500 ignition[704]: Ignition finished successfully Oct 27 23:28:09.033238 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 27 23:28:09.035283 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 27 23:28:09.036065 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 27 23:28:09.066712 ignition[818]: Ignition 2.22.0 Oct 27 23:28:09.066731 ignition[818]: Stage: kargs Oct 27 23:28:09.066874 ignition[818]: no configs at "/usr/lib/ignition/base.d" Oct 27 23:28:09.066883 ignition[818]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:28:09.069748 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 27 23:28:09.067470 ignition[818]: kargs: kargs passed Oct 27 23:28:09.067513 ignition[818]: Ignition finished successfully Oct 27 23:28:09.072293 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 27 23:28:09.106757 ignition[826]: Ignition 2.22.0 Oct 27 23:28:09.106776 ignition[826]: Stage: disks Oct 27 23:28:09.106928 ignition[826]: no configs at "/usr/lib/ignition/base.d" Oct 27 23:28:09.106937 ignition[826]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:28:09.107641 ignition[826]: disks: disks passed Oct 27 23:28:09.110481 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 27 23:28:09.107688 ignition[826]: Ignition finished successfully Oct 27 23:28:09.112620 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 27 23:28:09.114122 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 27 23:28:09.116200 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 27 23:28:09.117853 systemd[1]: Reached target sysinit.target - System Initialization. Oct 27 23:28:09.119804 systemd[1]: Reached target basic.target - Basic System. Oct 27 23:28:09.122642 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 27 23:28:09.154194 systemd-fsck[836]: ROOT: clean, 15/553520 files, 52789/553472 blocks Oct 27 23:28:09.158658 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 27 23:28:09.161717 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 27 23:28:09.229343 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 27 23:28:09.231067 kernel: EXT4-fs (vda9): mounted filesystem 8f9c7d7f-b094-48f2-af83-87ee7d7d8042 r/w with ordered data mode. Quota mode: none. Oct 27 23:28:09.230727 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 27 23:28:09.233442 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 27 23:28:09.235091 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 27 23:28:09.236180 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 27 23:28:09.236233 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 27 23:28:09.236254 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 27 23:28:09.247030 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 27 23:28:09.249882 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 27 23:28:09.252981 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (844) Oct 27 23:28:09.257242 kernel: BTRFS info (device vda6): first mount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:28:09.257273 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:28:09.261184 kernel: BTRFS info (device vda6): turning on async discard Oct 27 23:28:09.261209 kernel: BTRFS info (device vda6): enabling free space tree Oct 27 23:28:09.262991 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 27 23:28:09.288181 initrd-setup-root[868]: cut: /sysroot/etc/passwd: No such file or directory Oct 27 23:28:09.293093 initrd-setup-root[875]: cut: /sysroot/etc/group: No such file or directory Oct 27 23:28:09.297437 initrd-setup-root[882]: cut: /sysroot/etc/shadow: No such file or directory Oct 27 23:28:09.301628 initrd-setup-root[889]: cut: /sysroot/etc/gshadow: No such file or directory Oct 27 23:28:09.372480 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 27 23:28:09.374493 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 27 23:28:09.376163 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 27 23:28:09.399428 kernel: BTRFS info (device vda6): last unmount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:28:09.405667 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 27 23:28:09.429127 ignition[959]: INFO : Ignition 2.22.0 Oct 27 23:28:09.429127 ignition[959]: INFO : Stage: mount Oct 27 23:28:09.432161 ignition[959]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 23:28:09.432161 ignition[959]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:28:09.432161 ignition[959]: INFO : mount: mount passed Oct 27 23:28:09.432161 ignition[959]: INFO : Ignition finished successfully Oct 27 23:28:09.433389 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 27 23:28:09.436996 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 27 23:28:09.827946 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 27 23:28:09.829511 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 27 23:28:09.858414 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (970) Oct 27 23:28:09.861256 kernel: BTRFS info (device vda6): first mount of filesystem 1d73b0f7-269f-44d4-928d-157506a9bf3d Oct 27 23:28:09.861281 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 27 23:28:09.864167 kernel: BTRFS info (device vda6): turning on async discard Oct 27 23:28:09.864194 kernel: BTRFS info (device vda6): enabling free space tree Oct 27 23:28:09.865829 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 27 23:28:09.900099 ignition[988]: INFO : Ignition 2.22.0 Oct 27 23:28:09.900099 ignition[988]: INFO : Stage: files Oct 27 23:28:09.901992 ignition[988]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 23:28:09.901992 ignition[988]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:28:09.901992 ignition[988]: DEBUG : files: compiled without relabeling support, skipping Oct 27 23:28:09.901992 ignition[988]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 27 23:28:09.901992 ignition[988]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 27 23:28:09.908840 ignition[988]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 27 23:28:09.908840 ignition[988]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 27 23:28:09.908840 ignition[988]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 27 23:28:09.908840 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 27 23:28:09.908840 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 27 23:28:09.908840 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 27 23:28:09.908840 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 27 23:28:09.908840 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 27 23:28:09.904989 unknown[988]: wrote ssh authorized keys file for user: core Oct 27 23:28:09.923745 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 27 23:28:09.923745 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 27 23:28:09.923745 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Oct 27 23:28:10.277550 systemd-networkd[806]: eth0: Gained IPv6LL Oct 27 23:28:10.332070 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 27 23:28:10.599190 ignition[988]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 27 23:28:10.599190 ignition[988]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 27 23:28:10.602888 ignition[988]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 27 23:28:10.606511 ignition[988]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 27 23:28:10.606511 ignition[988]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 27 23:28:10.606511 ignition[988]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 27 23:28:10.620380 ignition[988]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 27 23:28:10.623999 ignition[988]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 27 23:28:10.627155 ignition[988]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 27 23:28:10.627155 ignition[988]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 27 23:28:10.627155 ignition[988]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 27 23:28:10.627155 ignition[988]: INFO : files: files passed Oct 27 23:28:10.627155 ignition[988]: INFO : Ignition finished successfully Oct 27 23:28:10.629449 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 27 23:28:10.632606 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 27 23:28:10.635141 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 27 23:28:10.647998 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 27 23:28:10.648131 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 27 23:28:10.652076 initrd-setup-root-after-ignition[1016]: grep: /sysroot/oem/oem-release: No such file or directory Oct 27 23:28:10.653664 initrd-setup-root-after-ignition[1018]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 27 23:28:10.653664 initrd-setup-root-after-ignition[1018]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 27 23:28:10.657360 initrd-setup-root-after-ignition[1022]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 27 23:28:10.656502 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 27 23:28:10.658887 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 27 23:28:10.660858 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 27 23:28:10.695997 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 27 23:28:10.696141 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 27 23:28:10.698624 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 27 23:28:10.700871 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 27 23:28:10.702982 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 27 23:28:10.703849 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 27 23:28:10.728441 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 27 23:28:10.731134 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 27 23:28:10.749566 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 27 23:28:10.750993 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 23:28:10.753328 systemd[1]: Stopped target timers.target - Timer Units. Oct 27 23:28:10.755463 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 27 23:28:10.755594 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 27 23:28:10.758513 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 27 23:28:10.760802 systemd[1]: Stopped target basic.target - Basic System. Oct 27 23:28:10.762630 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 27 23:28:10.764597 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 27 23:28:10.766904 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 27 23:28:10.769184 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 27 23:28:10.771386 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 27 23:28:10.773588 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 27 23:28:10.775845 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 27 23:28:10.778054 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 27 23:28:10.780136 systemd[1]: Stopped target swap.target - Swaps. Oct 27 23:28:10.781846 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 27 23:28:10.781991 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 27 23:28:10.784484 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 27 23:28:10.786643 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 23:28:10.788680 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 27 23:28:10.789585 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 23:28:10.790978 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 27 23:28:10.791100 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 27 23:28:10.794703 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 27 23:28:10.795009 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 27 23:28:10.797752 systemd[1]: Stopped target paths.target - Path Units. Oct 27 23:28:10.800145 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 27 23:28:10.800297 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 23:28:10.802717 systemd[1]: Stopped target slices.target - Slice Units. Oct 27 23:28:10.804544 systemd[1]: Stopped target sockets.target - Socket Units. Oct 27 23:28:10.806374 systemd[1]: iscsid.socket: Deactivated successfully. Oct 27 23:28:10.806527 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 27 23:28:10.808454 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 27 23:28:10.808574 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 27 23:28:10.810905 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 27 23:28:10.811071 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 27 23:28:10.812834 systemd[1]: ignition-files.service: Deactivated successfully. Oct 27 23:28:10.812978 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 27 23:28:10.815557 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 27 23:28:10.818080 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 27 23:28:10.819182 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 27 23:28:10.819360 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 23:28:10.821591 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 27 23:28:10.821737 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 27 23:28:10.828899 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 27 23:28:10.829544 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 27 23:28:10.838800 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 27 23:28:10.847951 ignition[1043]: INFO : Ignition 2.22.0 Oct 27 23:28:10.847951 ignition[1043]: INFO : Stage: umount Oct 27 23:28:10.850756 ignition[1043]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 27 23:28:10.850756 ignition[1043]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 27 23:28:10.850756 ignition[1043]: INFO : umount: umount passed Oct 27 23:28:10.850756 ignition[1043]: INFO : Ignition finished successfully Oct 27 23:28:10.852084 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 27 23:28:10.852209 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 27 23:28:10.854350 systemd[1]: Stopped target network.target - Network. Oct 27 23:28:10.855984 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 27 23:28:10.856043 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 27 23:28:10.858188 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 27 23:28:10.858238 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 27 23:28:10.861899 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 27 23:28:10.861951 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 27 23:28:10.865014 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 27 23:28:10.865060 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 27 23:28:10.867046 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 27 23:28:10.868900 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 27 23:28:10.876753 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 27 23:28:10.877607 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 27 23:28:10.882105 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 27 23:28:10.882334 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 27 23:28:10.882474 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 27 23:28:10.888283 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 27 23:28:10.888539 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 27 23:28:10.888624 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 27 23:28:10.891956 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 27 23:28:10.893922 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 27 23:28:10.893973 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 27 23:28:10.896319 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 27 23:28:10.896394 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 27 23:28:10.899486 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 27 23:28:10.900562 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 27 23:28:10.900624 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 27 23:28:10.902944 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 27 23:28:10.902991 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 27 23:28:10.906035 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 27 23:28:10.906081 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 27 23:28:10.908435 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 27 23:28:10.908486 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 23:28:10.912126 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 23:28:10.916315 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 27 23:28:10.916374 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 27 23:28:10.927036 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 27 23:28:10.933574 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 23:28:10.935330 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 27 23:28:10.935387 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 27 23:28:10.937682 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 27 23:28:10.937716 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 23:28:10.939777 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 27 23:28:10.939928 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 27 23:28:10.943130 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 27 23:28:10.943183 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 27 23:28:10.946376 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 27 23:28:10.946458 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 27 23:28:10.950459 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 27 23:28:10.951799 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 27 23:28:10.951859 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 27 23:28:10.955364 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 27 23:28:10.955432 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 23:28:10.959030 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 27 23:28:10.959076 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 23:28:10.962977 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 27 23:28:10.963022 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 23:28:10.965698 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 27 23:28:10.965749 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:28:10.970431 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Oct 27 23:28:10.970485 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Oct 27 23:28:10.970518 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 27 23:28:10.970548 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 27 23:28:10.970802 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 27 23:28:10.972529 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 27 23:28:10.978174 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 27 23:28:10.978276 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 27 23:28:10.979939 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 27 23:28:10.983234 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 27 23:28:11.008709 systemd[1]: Switching root. Oct 27 23:28:11.038931 systemd-journald[246]: Journal stopped Oct 27 23:28:11.828159 systemd-journald[246]: Received SIGTERM from PID 1 (systemd). Oct 27 23:28:11.828210 kernel: SELinux: policy capability network_peer_controls=1 Oct 27 23:28:11.828222 kernel: SELinux: policy capability open_perms=1 Oct 27 23:28:11.828237 kernel: SELinux: policy capability extended_socket_class=1 Oct 27 23:28:11.828251 kernel: SELinux: policy capability always_check_network=0 Oct 27 23:28:11.828260 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 27 23:28:11.828270 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 27 23:28:11.828282 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 27 23:28:11.828292 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 27 23:28:11.828302 kernel: SELinux: policy capability userspace_initial_context=0 Oct 27 23:28:11.828313 kernel: audit: type=1403 audit(1761607691.225:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 27 23:28:11.828326 systemd[1]: Successfully loaded SELinux policy in 63.262ms. Oct 27 23:28:11.828348 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.703ms. Oct 27 23:28:11.828363 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) Oct 27 23:28:11.828375 systemd[1]: Detected virtualization kvm. Oct 27 23:28:11.828385 systemd[1]: Detected architecture arm64. Oct 27 23:28:11.828395 systemd[1]: Detected first boot. Oct 27 23:28:11.828418 systemd[1]: Initializing machine ID from VM UUID. Oct 27 23:28:11.828430 zram_generator::config[1089]: No configuration found. Oct 27 23:28:11.828443 kernel: NET: Registered PF_VSOCK protocol family Oct 27 23:28:11.828454 systemd[1]: Populated /etc with preset unit settings. Oct 27 23:28:11.828465 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 27 23:28:11.828479 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 27 23:28:11.828489 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 27 23:28:11.828500 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 27 23:28:11.828510 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 27 23:28:11.828521 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 27 23:28:11.828532 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 27 23:28:11.828543 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 27 23:28:11.828553 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 27 23:28:11.828564 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 27 23:28:11.828574 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 27 23:28:11.828585 systemd[1]: Created slice user.slice - User and Session Slice. Oct 27 23:28:11.828596 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 27 23:28:11.828607 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 27 23:28:11.828617 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 27 23:28:11.828629 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 27 23:28:11.828640 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 27 23:28:11.828650 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 27 23:28:11.828660 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 27 23:28:11.828671 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 27 23:28:11.828682 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 27 23:28:11.828693 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 27 23:28:11.828704 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 27 23:28:11.828714 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 27 23:28:11.828725 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 27 23:28:11.828735 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 27 23:28:11.828746 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 27 23:28:11.828756 systemd[1]: Reached target slices.target - Slice Units. Oct 27 23:28:11.828766 systemd[1]: Reached target swap.target - Swaps. Oct 27 23:28:11.828782 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 27 23:28:11.828796 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 27 23:28:11.828809 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 27 23:28:11.828819 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 27 23:28:11.828830 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 27 23:28:11.828840 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 27 23:28:11.828850 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 27 23:28:11.828861 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 27 23:28:11.828871 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 27 23:28:11.828882 systemd[1]: Mounting media.mount - External Media Directory... Oct 27 23:28:11.828892 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 27 23:28:11.828904 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 27 23:28:11.828916 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 27 23:28:11.828927 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). Oct 27 23:28:11.828937 systemd[1]: Reached target machines.target - Containers. Oct 27 23:28:11.828948 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 27 23:28:11.828958 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:28:11.828969 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 27 23:28:11.828979 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 27 23:28:11.828989 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 23:28:11.829001 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 27 23:28:11.829012 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 23:28:11.829022 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 27 23:28:11.829033 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 23:28:11.829044 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 27 23:28:11.829054 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 27 23:28:11.829064 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 27 23:28:11.829074 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 27 23:28:11.829086 systemd[1]: Stopped systemd-fsck-usr.service. Oct 27 23:28:11.829097 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). Oct 27 23:28:11.829108 kernel: fuse: init (API version 7.41) Oct 27 23:28:11.829118 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 27 23:28:11.829132 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 27 23:28:11.829143 kernel: loop: module loaded Oct 27 23:28:11.829152 kernel: ACPI: bus type drm_connector registered Oct 27 23:28:11.829162 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 27 23:28:11.829173 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 27 23:28:11.829206 systemd-journald[1164]: Collecting audit messages is disabled. Oct 27 23:28:11.829232 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 27 23:28:11.829244 systemd-journald[1164]: Journal started Oct 27 23:28:11.829265 systemd-journald[1164]: Runtime Journal (/run/log/journal/a1b59278d8e744a4be1c6f5bf42fcd19) is 6M, max 48.5M, 42.4M free. Oct 27 23:28:11.592186 systemd[1]: Queued start job for default target multi-user.target. Oct 27 23:28:11.613468 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 27 23:28:11.613875 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 27 23:28:11.833430 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 27 23:28:11.837105 systemd[1]: verity-setup.service: Deactivated successfully. Oct 27 23:28:11.837153 systemd[1]: Stopped verity-setup.service. Oct 27 23:28:11.841621 systemd[1]: Started systemd-journald.service - Journal Service. Oct 27 23:28:11.842298 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 27 23:28:11.843761 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 27 23:28:11.845181 systemd[1]: Mounted media.mount - External Media Directory. Oct 27 23:28:11.846476 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 27 23:28:11.847888 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 27 23:28:11.849305 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 27 23:28:11.851483 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 27 23:28:11.853129 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 27 23:28:11.854749 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 27 23:28:11.856442 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 27 23:28:11.857978 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 23:28:11.858144 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 23:28:11.859662 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 23:28:11.859838 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 27 23:28:11.861319 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 23:28:11.861719 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 23:28:11.863268 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 27 23:28:11.863487 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 27 23:28:11.864965 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 23:28:11.865131 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 23:28:11.866805 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 27 23:28:11.869772 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 27 23:28:11.871647 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 27 23:28:11.873291 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 27 23:28:11.885327 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 27 23:28:11.887879 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 27 23:28:11.890091 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 27 23:28:11.891347 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 27 23:28:11.891376 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 27 23:28:11.893270 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 27 23:28:11.900256 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 27 23:28:11.901618 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:28:11.902889 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 27 23:28:11.905219 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 27 23:28:11.906996 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 27 23:28:11.908545 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 27 23:28:11.909918 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 27 23:28:11.911748 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 27 23:28:11.913395 systemd-journald[1164]: Time spent on flushing to /var/log/journal/a1b59278d8e744a4be1c6f5bf42fcd19 is 17.042ms for 872 entries. Oct 27 23:28:11.913395 systemd-journald[1164]: System Journal (/var/log/journal/a1b59278d8e744a4be1c6f5bf42fcd19) is 8M, max 195.6M, 187.6M free. Oct 27 23:28:11.938014 systemd-journald[1164]: Received client request to flush runtime journal. Oct 27 23:28:11.915838 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 27 23:28:11.918591 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 27 23:28:11.930736 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 27 23:28:11.932946 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 27 23:28:11.936477 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 27 23:28:11.941045 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 27 23:28:11.941477 kernel: loop0: detected capacity change from 0 to 100632 Oct 27 23:28:11.945893 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 27 23:28:11.949888 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 27 23:28:11.953011 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 27 23:28:11.956450 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 27 23:28:11.959763 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 27 23:28:11.966955 systemd-tmpfiles[1206]: ACLs are not supported, ignoring. Oct 27 23:28:11.966969 systemd-tmpfiles[1206]: ACLs are not supported, ignoring. Oct 27 23:28:11.970987 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 27 23:28:11.977424 kernel: loop1: detected capacity change from 0 to 119368 Oct 27 23:28:11.978808 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 27 23:28:11.988726 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 27 23:28:12.007424 kernel: loop2: detected capacity change from 0 to 211168 Oct 27 23:28:12.011095 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 27 23:28:12.018578 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 27 23:28:12.041166 systemd-tmpfiles[1226]: ACLs are not supported, ignoring. Oct 27 23:28:12.041184 systemd-tmpfiles[1226]: ACLs are not supported, ignoring. Oct 27 23:28:12.043507 kernel: loop3: detected capacity change from 0 to 100632 Oct 27 23:28:12.044388 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 27 23:28:12.050518 kernel: loop4: detected capacity change from 0 to 119368 Oct 27 23:28:12.058441 kernel: loop5: detected capacity change from 0 to 211168 Oct 27 23:28:12.064250 (sd-merge)[1229]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Oct 27 23:28:12.064671 (sd-merge)[1229]: Merged extensions into '/usr'. Oct 27 23:28:12.072223 systemd[1]: Reload requested from client PID 1205 ('systemd-sysext') (unit systemd-sysext.service)... Oct 27 23:28:12.072240 systemd[1]: Reloading... Oct 27 23:28:12.136452 zram_generator::config[1257]: No configuration found. Oct 27 23:28:12.187192 ldconfig[1200]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 27 23:28:12.275545 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 27 23:28:12.275716 systemd[1]: Reloading finished in 203 ms. Oct 27 23:28:12.313177 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 27 23:28:12.314845 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 27 23:28:12.331740 systemd[1]: Starting ensure-sysext.service... Oct 27 23:28:12.333841 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 27 23:28:12.348878 systemd[1]: Reload requested from client PID 1291 ('systemctl') (unit ensure-sysext.service)... Oct 27 23:28:12.348896 systemd[1]: Reloading... Oct 27 23:28:12.353215 systemd-tmpfiles[1292]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 27 23:28:12.353246 systemd-tmpfiles[1292]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 27 23:28:12.353534 systemd-tmpfiles[1292]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 27 23:28:12.353727 systemd-tmpfiles[1292]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 27 23:28:12.354679 systemd-tmpfiles[1292]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 27 23:28:12.354995 systemd-tmpfiles[1292]: ACLs are not supported, ignoring. Oct 27 23:28:12.355078 systemd-tmpfiles[1292]: ACLs are not supported, ignoring. Oct 27 23:28:12.359310 systemd-tmpfiles[1292]: Detected autofs mount point /boot during canonicalization of boot. Oct 27 23:28:12.359321 systemd-tmpfiles[1292]: Skipping /boot Oct 27 23:28:12.366318 systemd-tmpfiles[1292]: Detected autofs mount point /boot during canonicalization of boot. Oct 27 23:28:12.366449 systemd-tmpfiles[1292]: Skipping /boot Oct 27 23:28:12.399550 zram_generator::config[1319]: No configuration found. Oct 27 23:28:12.530658 systemd[1]: Reloading finished in 181 ms. Oct 27 23:28:12.553186 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 27 23:28:12.559329 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 27 23:28:12.571484 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 27 23:28:12.589435 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 27 23:28:12.591923 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 27 23:28:12.594954 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 27 23:28:12.597459 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 27 23:28:12.602740 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 27 23:28:12.616605 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 27 23:28:12.620306 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 27 23:28:12.626949 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:28:12.631609 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 23:28:12.634540 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 23:28:12.637907 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 23:28:12.640573 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:28:12.640830 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). Oct 27 23:28:12.644807 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 27 23:28:12.648567 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 23:28:12.650444 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 23:28:12.653311 systemd-udevd[1360]: Using default interface naming scheme 'v255'. Oct 27 23:28:12.653337 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 23:28:12.654500 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 23:28:12.659248 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 27 23:28:12.661502 augenrules[1386]: No rules Oct 27 23:28:12.661628 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 23:28:12.661797 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 23:28:12.663798 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 23:28:12.663996 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 27 23:28:12.665875 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 27 23:28:12.670280 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 27 23:28:12.677186 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 27 23:28:12.681043 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:28:12.682648 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 27 23:28:12.685111 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 27 23:28:12.687673 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 23:28:12.689607 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:28:12.689724 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). Oct 27 23:28:12.698742 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 27 23:28:12.699959 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). Oct 27 23:28:12.700927 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 27 23:28:12.703083 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 23:28:12.703293 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 23:28:12.709215 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 27 23:28:12.710490 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 27 23:28:12.714103 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 27 23:28:12.715573 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 27 23:28:12.723614 systemd[1]: Finished ensure-sysext.service. Oct 27 23:28:12.728147 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 27 23:28:12.730653 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 27 23:28:12.732103 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 27 23:28:12.740717 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 27 23:28:12.741943 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 27 23:28:12.741991 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). Oct 27 23:28:12.742033 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 27 23:28:12.746873 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 27 23:28:12.748497 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). Oct 27 23:28:12.758772 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 27 23:28:12.765456 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 27 23:28:12.767019 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 27 23:28:12.767183 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 27 23:28:12.775440 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 27 23:28:12.775747 augenrules[1439]: /sbin/augenrules: No change Oct 27 23:28:12.784941 augenrules[1466]: No rules Oct 27 23:28:12.786596 systemd[1]: audit-rules.service: Deactivated successfully. Oct 27 23:28:12.786811 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 27 23:28:12.790543 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 27 23:28:12.838284 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 27 23:28:12.842558 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 27 23:28:12.867352 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 27 23:28:12.882238 systemd-networkd[1427]: lo: Link UP Oct 27 23:28:12.882255 systemd-networkd[1427]: lo: Gained carrier Oct 27 23:28:12.883177 systemd-networkd[1427]: Enumeration completed Oct 27 23:28:12.883300 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 27 23:28:12.884685 systemd-networkd[1427]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:28:12.884698 systemd-networkd[1427]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 27 23:28:12.885345 systemd-networkd[1427]: eth0: Link UP Oct 27 23:28:12.885561 systemd-networkd[1427]: eth0: Gained carrier Oct 27 23:28:12.885581 systemd-networkd[1427]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 27 23:28:12.886382 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 27 23:28:12.889720 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 27 23:28:12.892537 systemd-resolved[1359]: Positive Trust Anchors: Oct 27 23:28:12.893261 systemd-resolved[1359]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 27 23:28:12.893299 systemd-resolved[1359]: 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 Oct 27 23:28:12.895705 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 27 23:28:12.897162 systemd[1]: Reached target time-set.target - System Time Set. Oct 27 23:28:12.899513 systemd-networkd[1427]: eth0: DHCPv4 address 10.0.0.33/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 27 23:28:12.904759 systemd-resolved[1359]: Defaulting to hostname 'linux'. Oct 27 23:28:12.904815 systemd-timesyncd[1442]: Network configuration changed, trying to establish connection. Oct 27 23:28:12.906277 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 27 23:28:12.906882 systemd-timesyncd[1442]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 27 23:28:12.906931 systemd-timesyncd[1442]: Initial clock synchronization to Mon 2025-10-27 23:28:12.967564 UTC. Oct 27 23:28:12.908016 systemd[1]: Reached target network.target - Network. Oct 27 23:28:12.909244 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 27 23:28:12.910640 systemd[1]: Reached target sysinit.target - System Initialization. Oct 27 23:28:12.912178 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 27 23:28:12.913577 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 27 23:28:12.915135 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 27 23:28:12.916429 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 27 23:28:12.917730 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 27 23:28:12.919094 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 27 23:28:12.919133 systemd[1]: Reached target paths.target - Path Units. Oct 27 23:28:12.920483 systemd[1]: Reached target timers.target - Timer Units. Oct 27 23:28:12.922257 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 27 23:28:12.924894 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 27 23:28:12.927922 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 27 23:28:12.930738 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 27 23:28:12.932118 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 27 23:28:12.936289 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 27 23:28:12.937869 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 27 23:28:12.941435 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 27 23:28:12.943093 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 27 23:28:12.944987 systemd[1]: Reached target sockets.target - Socket Units. Oct 27 23:28:12.946283 systemd[1]: Reached target basic.target - Basic System. Oct 27 23:28:12.948044 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 27 23:28:12.948080 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 27 23:28:12.949379 systemd[1]: Starting containerd.service - containerd container runtime... Oct 27 23:28:12.952627 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 27 23:28:12.956736 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 27 23:28:12.959598 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 27 23:28:12.977559 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 27 23:28:12.978785 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). Oct 27 23:28:12.980090 jq[1500]: false Oct 27 23:28:12.981669 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 27 23:28:12.983922 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 27 23:28:12.987623 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 27 23:28:12.990881 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 27 23:28:12.992937 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 27 23:28:12.994265 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 27 23:28:12.995610 systemd[1]: Starting update-engine.service - Update Engine... Oct 27 23:28:12.998017 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 27 23:28:13.003461 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 27 23:28:13.008054 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 27 23:28:13.008274 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 27 23:28:13.008540 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 27 23:28:13.008709 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 27 23:28:13.017522 jq[1510]: true Oct 27 23:28:13.020226 systemd[1]: motdgen.service: Deactivated successfully. Oct 27 23:28:13.021011 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 27 23:28:13.023071 (ntainerd)[1518]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 27 23:28:13.033283 update_engine[1508]: I20251027 23:28:13.032939 1508 main.cc:92] Flatcar Update Engine starting Oct 27 23:28:13.035612 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 27 23:28:13.037918 jq[1525]: true Oct 27 23:28:13.038580 extend-filesystems[1502]: Found /dev/vda6 Oct 27 23:28:13.044720 extend-filesystems[1502]: Found /dev/vda9 Oct 27 23:28:13.048214 extend-filesystems[1502]: Checking size of /dev/vda9 Oct 27 23:28:13.060581 dbus-daemon[1497]: [system] SELinux support is enabled Oct 27 23:28:13.060772 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 27 23:28:13.064746 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). Oct 27 23:28:13.064787 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 27 23:28:13.066319 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). Oct 27 23:28:13.066337 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 27 23:28:13.067871 systemd[1]: Started update-engine.service - Update Engine. Oct 27 23:28:13.068225 update_engine[1508]: I20251027 23:28:13.067916 1508 update_check_scheduler.cc:74] Next update check in 2m47s Oct 27 23:28:13.069655 systemd-logind[1506]: Watching system buttons on /dev/input/event0 (Power Button) Oct 27 23:28:13.070821 systemd-logind[1506]: New seat seat0. Oct 27 23:28:13.071547 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 27 23:28:13.071694 extend-filesystems[1502]: Resized partition /dev/vda9 Oct 27 23:28:13.075474 systemd[1]: Started systemd-logind.service - User Login Management. Oct 27 23:28:13.082342 extend-filesystems[1552]: resize2fs 1.47.3 (8-Jul-2025) Oct 27 23:28:13.085901 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Oct 27 23:28:13.124432 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Oct 27 23:28:13.147441 bash[1556]: Updated "/home/core/.ssh/authorized_keys" Oct 27 23:28:13.147629 extend-filesystems[1552]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 27 23:28:13.147629 extend-filesystems[1552]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 27 23:28:13.147629 extend-filesystems[1552]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Oct 27 23:28:13.160674 extend-filesystems[1502]: Resized filesystem in /dev/vda9 Oct 27 23:28:13.150456 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 27 23:28:13.159480 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 27 23:28:13.161462 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 27 23:28:13.166819 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 27 23:28:13.171638 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 27 23:28:13.184798 locksmithd[1550]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 27 23:28:13.224147 containerd[1518]: time="2025-10-27T23:28:13Z" 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 Oct 27 23:28:13.228628 containerd[1518]: time="2025-10-27T23:28:13.227054044Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 27 23:28:13.236145 containerd[1518]: time="2025-10-27T23:28:13.236091366Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.842µs" Oct 27 23:28:13.236145 containerd[1518]: time="2025-10-27T23:28:13.236133081Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 27 23:28:13.236233 containerd[1518]: time="2025-10-27T23:28:13.236153676Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 27 23:28:13.236335 containerd[1518]: time="2025-10-27T23:28:13.236314690Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 27 23:28:13.236360 containerd[1518]: time="2025-10-27T23:28:13.236336575Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 27 23:28:13.236378 containerd[1518]: time="2025-10-27T23:28:13.236363054Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:28:13.236449 containerd[1518]: time="2025-10-27T23:28:13.236431490Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 27 23:28:13.236473 containerd[1518]: time="2025-10-27T23:28:13.236447813Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 27 23:28:13.236727 containerd[1518]: time="2025-10-27T23:28:13.236691853Z" 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 Oct 27 23:28:13.236727 containerd[1518]: time="2025-10-27T23:28:13.236714463Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:28:13.236770 containerd[1518]: time="2025-10-27T23:28:13.236726353Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 27 23:28:13.236770 containerd[1518]: time="2025-10-27T23:28:13.236735099Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 27 23:28:13.236825 containerd[1518]: time="2025-10-27T23:28:13.236807525Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 27 23:28:13.237045 containerd[1518]: time="2025-10-27T23:28:13.237025085Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 27 23:28:13.237077 containerd[1518]: time="2025-10-27T23:28:13.237063051Z" 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 Oct 27 23:28:13.237108 containerd[1518]: time="2025-10-27T23:28:13.237076955Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 27 23:28:13.237135 containerd[1518]: time="2025-10-27T23:28:13.237108191Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 27 23:28:13.237463 containerd[1518]: time="2025-10-27T23:28:13.237444526Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 27 23:28:13.237537 containerd[1518]: time="2025-10-27T23:28:13.237520136Z" level=info msg="metadata content store policy set" policy=shared Oct 27 23:28:13.241286 containerd[1518]: time="2025-10-27T23:28:13.241240903Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 27 23:28:13.241326 containerd[1518]: time="2025-10-27T23:28:13.241315828Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 27 23:28:13.241345 containerd[1518]: time="2025-10-27T23:28:13.241330942Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 27 23:28:13.241363 containerd[1518]: time="2025-10-27T23:28:13.241342992Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 27 23:28:13.241450 containerd[1518]: time="2025-10-27T23:28:13.241355446Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 27 23:28:13.241450 containerd[1518]: time="2025-10-27T23:28:13.241428638Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 27 23:28:13.241450 containerd[1518]: time="2025-10-27T23:28:13.241448185Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 27 23:28:13.241514 containerd[1518]: time="2025-10-27T23:28:13.241461607Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 27 23:28:13.241514 containerd[1518]: time="2025-10-27T23:28:13.241474665Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 27 23:28:13.241514 containerd[1518]: time="2025-10-27T23:28:13.241485950Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 27 23:28:13.241514 containerd[1518]: time="2025-10-27T23:28:13.241495784Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 27 23:28:13.241514 containerd[1518]: time="2025-10-27T23:28:13.241509487Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 27 23:28:13.241676 containerd[1518]: time="2025-10-27T23:28:13.241655508Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 27 23:28:13.241700 containerd[1518]: time="2025-10-27T23:28:13.241690855Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 27 23:28:13.241719 containerd[1518]: time="2025-10-27T23:28:13.241712216Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 27 23:28:13.241737 containerd[1518]: time="2025-10-27T23:28:13.241723662Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 27 23:28:13.241756 containerd[1518]: time="2025-10-27T23:28:13.241735189Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 27 23:28:13.241756 containerd[1518]: time="2025-10-27T23:28:13.241746958Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 27 23:28:13.241799 containerd[1518]: time="2025-10-27T23:28:13.241758444Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 27 23:28:13.241799 containerd[1518]: time="2025-10-27T23:28:13.241770817Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 27 23:28:13.241799 containerd[1518]: time="2025-10-27T23:28:13.241783191Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 27 23:28:13.241799 containerd[1518]: time="2025-10-27T23:28:13.241794395Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 27 23:28:13.241872 containerd[1518]: time="2025-10-27T23:28:13.241810275Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 27 23:28:13.242027 containerd[1518]: time="2025-10-27T23:28:13.241998534Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 27 23:28:13.242027 containerd[1518]: time="2025-10-27T23:28:13.242019008Z" level=info msg="Start snapshots syncer" Oct 27 23:28:13.242076 containerd[1518]: time="2025-10-27T23:28:13.242049558Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 27 23:28:13.242339 containerd[1518]: time="2025-10-27T23:28:13.242293558Z" 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\"}" Oct 27 23:28:13.242445 containerd[1518]: time="2025-10-27T23:28:13.242351071Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 27 23:28:13.242483 containerd[1518]: time="2025-10-27T23:28:13.242463720Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 27 23:28:13.242623 containerd[1518]: time="2025-10-27T23:28:13.242594466Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 27 23:28:13.242658 containerd[1518]: time="2025-10-27T23:28:13.242633843Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 27 23:28:13.242658 containerd[1518]: time="2025-10-27T23:28:13.242645773Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 27 23:28:13.242698 containerd[1518]: time="2025-10-27T23:28:13.242659234Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 27 23:28:13.242698 containerd[1518]: time="2025-10-27T23:28:13.242672091Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 27 23:28:13.242698 containerd[1518]: time="2025-10-27T23:28:13.242683054Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 27 23:28:13.242698 containerd[1518]: time="2025-10-27T23:28:13.242695709Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 27 23:28:13.242770 containerd[1518]: time="2025-10-27T23:28:13.242727912Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 27 23:28:13.242770 containerd[1518]: time="2025-10-27T23:28:13.242741736Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 27 23:28:13.242770 containerd[1518]: time="2025-10-27T23:28:13.242752658Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 27 23:28:13.242821 containerd[1518]: time="2025-10-27T23:28:13.242795300Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 27 23:28:13.242821 containerd[1518]: time="2025-10-27T23:28:13.242811986Z" 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 Oct 27 23:28:13.242856 containerd[1518]: time="2025-10-27T23:28:13.242820812Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:28:13.242856 containerd[1518]: time="2025-10-27T23:28:13.242830445Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 27 23:28:13.242856 containerd[1518]: time="2025-10-27T23:28:13.242838223Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 27 23:28:13.242856 containerd[1518]: time="2025-10-27T23:28:13.242847614Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 27 23:28:13.242927 containerd[1518]: time="2025-10-27T23:28:13.242858093Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 27 23:28:13.242975 containerd[1518]: time="2025-10-27T23:28:13.242958933Z" level=info msg="runtime interface created" Oct 27 23:28:13.242975 containerd[1518]: time="2025-10-27T23:28:13.242971750Z" level=info msg="created NRI interface" Oct 27 23:28:13.243012 containerd[1518]: time="2025-10-27T23:28:13.242986904Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 27 23:28:13.243012 containerd[1518]: time="2025-10-27T23:28:13.243000849Z" level=info msg="Connect containerd service" Oct 27 23:28:13.243051 containerd[1518]: time="2025-10-27T23:28:13.243028699Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 27 23:28:13.243960 containerd[1518]: time="2025-10-27T23:28:13.243931383Z" 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" Oct 27 23:28:13.293550 sshd_keygen[1523]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 27 23:28:13.313012 containerd[1518]: time="2025-10-27T23:28:13.312952054Z" level=info msg="Start subscribing containerd event" Oct 27 23:28:13.313143 containerd[1518]: time="2025-10-27T23:28:13.313031413Z" level=info msg="Start recovering state" Oct 27 23:28:13.313194 containerd[1518]: time="2025-10-27T23:28:13.313165020Z" level=info msg="Start event monitor" Oct 27 23:28:13.313194 containerd[1518]: time="2025-10-27T23:28:13.313180617Z" level=info msg="Start cni network conf syncer for default" Oct 27 23:28:13.313194 containerd[1518]: time="2025-10-27T23:28:13.313188557Z" level=info msg="Start streaming server" Oct 27 23:28:13.313249 containerd[1518]: time="2025-10-27T23:28:13.313198230Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 27 23:28:13.313249 containerd[1518]: time="2025-10-27T23:28:13.313206654Z" level=info msg="runtime interface starting up..." Oct 27 23:28:13.313249 containerd[1518]: time="2025-10-27T23:28:13.313213465Z" level=info msg="starting plugins..." Oct 27 23:28:13.313249 containerd[1518]: time="2025-10-27T23:28:13.313226322Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 27 23:28:13.313440 containerd[1518]: time="2025-10-27T23:28:13.313376977Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 27 23:28:13.313492 containerd[1518]: time="2025-10-27T23:28:13.313478624Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 27 23:28:13.313567 containerd[1518]: time="2025-10-27T23:28:13.313553548Z" level=info msg="containerd successfully booted in 0.089763s" Oct 27 23:28:13.313691 systemd[1]: Started containerd.service - containerd container runtime. Oct 27 23:28:13.315705 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 27 23:28:13.319919 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 27 23:28:13.349196 systemd[1]: issuegen.service: Deactivated successfully. Oct 27 23:28:13.349481 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 27 23:28:13.352474 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 27 23:28:13.383472 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 27 23:28:13.386535 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 27 23:28:13.388779 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 27 23:28:13.390305 systemd[1]: Reached target getty.target - Login Prompts. Oct 27 23:28:14.885793 systemd-networkd[1427]: eth0: Gained IPv6LL Oct 27 23:28:14.888083 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 27 23:28:14.889983 systemd[1]: Reached target network-online.target - Network is Online. Oct 27 23:28:14.892538 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 27 23:28:14.894966 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 27 23:28:14.907809 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 27 23:28:14.924988 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 27 23:28:14.925238 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 27 23:28:14.927783 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 27 23:28:14.930124 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 27 23:28:15.457329 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 23:28:15.459042 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 27 23:28:15.460943 systemd[1]: Startup finished in 2.067s (kernel) + 4.607s (initrd) + 4.299s (userspace) = 10.974s. Oct 27 23:28:15.461030 (kubelet)[1629]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 27 23:28:15.811543 kubelet[1629]: E1027 23:28:15.811430 1629 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" Oct 27 23:28:15.814051 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 27 23:28:15.814202 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 27 23:28:15.814561 systemd[1]: kubelet.service: Consumed 748ms CPU time, 258.1M memory peak. Oct 27 23:28:19.769954 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 27 23:28:19.770933 systemd[1]: Started sshd@0-10.0.0.33:22-10.0.0.1:46454.service - OpenSSH per-connection server daemon (10.0.0.1:46454). Oct 27 23:28:19.843616 sshd[1642]: Accepted publickey for core from 10.0.0.1 port 46454 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:28:19.845620 sshd-session[1642]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:28:19.852135 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 27 23:28:19.853740 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 27 23:28:19.861231 systemd-logind[1506]: New session 1 of user core. Oct 27 23:28:19.890452 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 27 23:28:19.893017 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 27 23:28:19.911694 (systemd)[1647]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 27 23:28:19.913845 systemd-logind[1506]: New session c1 of user core. Oct 27 23:28:20.016809 systemd[1647]: Queued start job for default target default.target. Oct 27 23:28:20.038362 systemd[1647]: Created slice app.slice - User Application Slice. Oct 27 23:28:20.038393 systemd[1647]: Reached target paths.target - Paths. Oct 27 23:28:20.038456 systemd[1647]: Reached target timers.target - Timers. Oct 27 23:28:20.039648 systemd[1647]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 27 23:28:20.049819 systemd[1647]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 27 23:28:20.049879 systemd[1647]: Reached target sockets.target - Sockets. Oct 27 23:28:20.049916 systemd[1647]: Reached target basic.target - Basic System. Oct 27 23:28:20.049944 systemd[1647]: Reached target default.target - Main User Target. Oct 27 23:28:20.049969 systemd[1647]: Startup finished in 130ms. Oct 27 23:28:20.050197 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 27 23:28:20.052459 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 27 23:28:20.116825 systemd[1]: Started sshd@1-10.0.0.33:22-10.0.0.1:46464.service - OpenSSH per-connection server daemon (10.0.0.1:46464). Oct 27 23:28:20.169234 sshd[1658]: Accepted publickey for core from 10.0.0.1 port 46464 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:28:20.170621 sshd-session[1658]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:28:20.175268 systemd-logind[1506]: New session 2 of user core. Oct 27 23:28:20.182561 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 27 23:28:20.234461 sshd[1661]: Connection closed by 10.0.0.1 port 46464 Oct 27 23:28:20.234763 sshd-session[1658]: pam_unix(sshd:session): session closed for user core Oct 27 23:28:20.244708 systemd[1]: sshd@1-10.0.0.33:22-10.0.0.1:46464.service: Deactivated successfully. Oct 27 23:28:20.246175 systemd[1]: session-2.scope: Deactivated successfully. Oct 27 23:28:20.246866 systemd-logind[1506]: Session 2 logged out. Waiting for processes to exit. Oct 27 23:28:20.249055 systemd[1]: Started sshd@2-10.0.0.33:22-10.0.0.1:46470.service - OpenSSH per-connection server daemon (10.0.0.1:46470). Oct 27 23:28:20.249972 systemd-logind[1506]: Removed session 2. Oct 27 23:28:20.308254 sshd[1667]: Accepted publickey for core from 10.0.0.1 port 46470 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:28:20.309388 sshd-session[1667]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:28:20.313329 systemd-logind[1506]: New session 3 of user core. Oct 27 23:28:20.325609 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 27 23:28:20.373082 sshd[1671]: Connection closed by 10.0.0.1 port 46470 Oct 27 23:28:20.373698 sshd-session[1667]: pam_unix(sshd:session): session closed for user core Oct 27 23:28:20.391839 systemd[1]: sshd@2-10.0.0.33:22-10.0.0.1:46470.service: Deactivated successfully. Oct 27 23:28:20.395671 systemd[1]: session-3.scope: Deactivated successfully. Oct 27 23:28:20.397360 systemd-logind[1506]: Session 3 logged out. Waiting for processes to exit. Oct 27 23:28:20.399303 systemd[1]: Started sshd@3-10.0.0.33:22-10.0.0.1:46478.service - OpenSSH per-connection server daemon (10.0.0.1:46478). Oct 27 23:28:20.401003 systemd-logind[1506]: Removed session 3. Oct 27 23:28:20.447513 sshd[1677]: Accepted publickey for core from 10.0.0.1 port 46478 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:28:20.448740 sshd-session[1677]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:28:20.452839 systemd-logind[1506]: New session 4 of user core. Oct 27 23:28:20.467583 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 27 23:28:20.518932 sshd[1680]: Connection closed by 10.0.0.1 port 46478 Oct 27 23:28:20.519579 sshd-session[1677]: pam_unix(sshd:session): session closed for user core Oct 27 23:28:20.528258 systemd[1]: sshd@3-10.0.0.33:22-10.0.0.1:46478.service: Deactivated successfully. Oct 27 23:28:20.530700 systemd[1]: session-4.scope: Deactivated successfully. Oct 27 23:28:20.531341 systemd-logind[1506]: Session 4 logged out. Waiting for processes to exit. Oct 27 23:28:20.533236 systemd[1]: Started sshd@4-10.0.0.33:22-10.0.0.1:46480.service - OpenSSH per-connection server daemon (10.0.0.1:46480). Oct 27 23:28:20.534620 systemd-logind[1506]: Removed session 4. Oct 27 23:28:20.573364 sshd[1686]: Accepted publickey for core from 10.0.0.1 port 46480 ssh2: RSA SHA256:rJd+TU7sFfM9uyplsLTyQyJ9SbIIl66cWvxItQSjr84 Oct 27 23:28:20.574559 sshd-session[1686]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 27 23:28:20.578450 systemd-logind[1506]: New session 5 of user core. Oct 27 23:28:20.588615 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 27 23:28:20.643527 sudo[1690]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 27 23:28:20.643781 sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 27 23:28:20.654859 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 27 23:28:20.688653 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 27 23:28:20.690523 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 27 23:28:21.093008 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 23:28:21.093233 systemd[1]: kubelet.service: Consumed 748ms CPU time, 258.1M memory peak. Oct 27 23:28:21.096373 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 27 23:28:21.114751 systemd[1]: Reload requested from client PID 1733 ('systemctl') (unit session-5.scope)... Oct 27 23:28:21.114766 systemd[1]: Reloading... Oct 27 23:28:21.188456 zram_generator::config[1778]: No configuration found. Oct 27 23:28:21.430186 systemd[1]: Reloading finished in 315 ms. Oct 27 23:28:21.490955 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 27 23:28:21.491042 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 27 23:28:21.491372 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 23:28:21.491444 systemd[1]: kubelet.service: Consumed 92ms CPU time, 95M memory peak. Oct 27 23:28:21.493136 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 27 23:28:21.603659 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 27 23:28:21.607522 (kubelet)[1820]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 27 23:28:21.638621 kubelet[1820]: 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. Oct 27 23:28:21.638621 kubelet[1820]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 27 23:28:21.638621 kubelet[1820]: 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. Oct 27 23:28:21.638945 kubelet[1820]: I1027 23:28:21.638671 1820 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Oct 27 23:28:22.724453 kubelet[1820]: I1027 23:28:22.723567 1820 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Oct 27 23:28:22.724453 kubelet[1820]: I1027 23:28:22.723598 1820 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 27 23:28:22.724453 kubelet[1820]: I1027 23:28:22.723812 1820 server.go:956] "Client rotation is on, will bootstrap in background" Oct 27 23:28:22.744070 kubelet[1820]: I1027 23:28:22.744008 1820 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 27 23:28:22.754381 kubelet[1820]: I1027 23:28:22.754341 1820 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 27 23:28:22.757273 kubelet[1820]: I1027 23:28:22.757248 1820 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 27 23:28:22.758407 kubelet[1820]: I1027 23:28:22.758349 1820 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 27 23:28:22.758564 kubelet[1820]: I1027 23:28:22.758392 1820 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.33","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":"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},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 27 23:28:22.758687 kubelet[1820]: I1027 23:28:22.758674 1820 topology_manager.go:138] "Creating topology manager with none policy" Oct 27 23:28:22.758687 kubelet[1820]: I1027 23:28:22.758683 1820 container_manager_linux.go:303] "Creating device plugin manager" Oct 27 23:28:22.759476 kubelet[1820]: I1027 23:28:22.759435 1820 state_mem.go:36] "Initialized new in-memory state store" Oct 27 23:28:22.762404 kubelet[1820]: I1027 23:28:22.762375 1820 kubelet.go:480] "Attempting to sync node with API server" Oct 27 23:28:22.762452 kubelet[1820]: I1027 23:28:22.762412 1820 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 27 23:28:22.763039 kubelet[1820]: I1027 23:28:22.763009 1820 kubelet.go:386] "Adding apiserver pod source" Oct 27 23:28:22.764179 kubelet[1820]: I1027 23:28:22.764110 1820 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 27 23:28:22.764179 kubelet[1820]: E1027 23:28:22.764132 1820 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:22.764179 kubelet[1820]: E1027 23:28:22.764146 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:22.765445 kubelet[1820]: I1027 23:28:22.765373 1820 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Oct 27 23:28:22.766328 kubelet[1820]: I1027 23:28:22.766295 1820 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 27 23:28:22.766518 kubelet[1820]: W1027 23:28:22.766494 1820 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 27 23:28:22.769914 kubelet[1820]: I1027 23:28:22.769896 1820 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 27 23:28:22.769976 kubelet[1820]: I1027 23:28:22.769944 1820 server.go:1289] "Started kubelet" Oct 27 23:28:22.771213 kubelet[1820]: I1027 23:28:22.771179 1820 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 27 23:28:22.773209 kubelet[1820]: I1027 23:28:22.773160 1820 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Oct 27 23:28:22.774201 kubelet[1820]: I1027 23:28:22.774169 1820 server.go:317] "Adding debug handlers to kubelet server" Oct 27 23:28:22.777209 kubelet[1820]: E1027 23:28:22.777172 1820 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 27 23:28:22.777381 kubelet[1820]: I1027 23:28:22.777355 1820 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 27 23:28:22.778070 kubelet[1820]: I1027 23:28:22.777765 1820 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 27 23:28:22.778070 kubelet[1820]: E1027 23:28:22.778046 1820 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.33\" not found" Oct 27 23:28:22.778172 kubelet[1820]: I1027 23:28:22.778147 1820 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 27 23:28:22.778205 kubelet[1820]: I1027 23:28:22.778195 1820 reconciler.go:26] "Reconciler: start to sync state" Oct 27 23:28:22.778378 kubelet[1820]: I1027 23:28:22.778245 1820 factory.go:223] Registration of the systemd container factory successfully Oct 27 23:28:22.778378 kubelet[1820]: I1027 23:28:22.778339 1820 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Oct 27 23:28:22.779502 kubelet[1820]: I1027 23:28:22.779291 1820 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 27 23:28:22.779599 kubelet[1820]: I1027 23:28:22.779542 1820 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 27 23:28:22.781478 kubelet[1820]: I1027 23:28:22.780805 1820 factory.go:223] Registration of the containerd container factory successfully Oct 27 23:28:22.786387 kubelet[1820]: E1027 23:28:22.786354 1820 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.33\" not found" node="10.0.0.33" Oct 27 23:28:22.795450 kubelet[1820]: I1027 23:28:22.795383 1820 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 27 23:28:22.795709 kubelet[1820]: I1027 23:28:22.795669 1820 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 27 23:28:22.795795 kubelet[1820]: I1027 23:28:22.795785 1820 state_mem.go:36] "Initialized new in-memory state store" Oct 27 23:28:22.878205 kubelet[1820]: E1027 23:28:22.878127 1820 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.33\" not found" Oct 27 23:28:22.885845 kubelet[1820]: I1027 23:28:22.885757 1820 policy_none.go:49] "None policy: Start" Oct 27 23:28:22.885845 kubelet[1820]: I1027 23:28:22.885791 1820 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 27 23:28:22.885845 kubelet[1820]: I1027 23:28:22.885803 1820 state_mem.go:35] "Initializing new in-memory state store" Oct 27 23:28:22.892814 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 27 23:28:22.906766 kubelet[1820]: I1027 23:28:22.906718 1820 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Oct 27 23:28:22.906763 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 27 23:28:22.907628 kubelet[1820]: I1027 23:28:22.907597 1820 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Oct 27 23:28:22.907628 kubelet[1820]: I1027 23:28:22.907619 1820 status_manager.go:230] "Starting to sync pod status with apiserver" Oct 27 23:28:22.907711 kubelet[1820]: I1027 23:28:22.907638 1820 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 27 23:28:22.907711 kubelet[1820]: I1027 23:28:22.907644 1820 kubelet.go:2436] "Starting kubelet main sync loop" Oct 27 23:28:22.907711 kubelet[1820]: E1027 23:28:22.907683 1820 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Oct 27 23:28:22.920248 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 27 23:28:22.921700 kubelet[1820]: E1027 23:28:22.921676 1820 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 27 23:28:22.922377 kubelet[1820]: I1027 23:28:22.922145 1820 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 27 23:28:22.922377 kubelet[1820]: I1027 23:28:22.922166 1820 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 27 23:28:22.922534 kubelet[1820]: I1027 23:28:22.922523 1820 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 27 23:28:22.923450 kubelet[1820]: E1027 23:28:22.923425 1820 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 27 23:28:22.923509 kubelet[1820]: E1027 23:28:22.923495 1820 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.33\" not found" Oct 27 23:28:23.023118 kubelet[1820]: I1027 23:28:23.023012 1820 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.33" Oct 27 23:28:23.030526 kubelet[1820]: I1027 23:28:23.030492 1820 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.33" Oct 27 23:28:23.136274 sudo[1690]: pam_unix(sudo:session): session closed for user root Oct 27 23:28:23.137880 sshd[1689]: Connection closed by 10.0.0.1 port 46480 Oct 27 23:28:23.138197 sshd-session[1686]: pam_unix(sshd:session): session closed for user core Oct 27 23:28:23.139223 kubelet[1820]: I1027 23:28:23.139200 1820 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 27 23:28:23.139491 containerd[1518]: time="2025-10-27T23:28:23.139456454Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 27 23:28:23.140085 kubelet[1820]: I1027 23:28:23.139924 1820 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 27 23:28:23.142724 systemd[1]: sshd@4-10.0.0.33:22-10.0.0.1:46480.service: Deactivated successfully. Oct 27 23:28:23.144671 systemd[1]: session-5.scope: Deactivated successfully. Oct 27 23:28:23.144989 systemd[1]: session-5.scope: Consumed 408ms CPU time, 76.9M memory peak. Oct 27 23:28:23.146773 systemd-logind[1506]: Session 5 logged out. Waiting for processes to exit. Oct 27 23:28:23.148307 systemd-logind[1506]: Removed session 5. Oct 27 23:28:23.726219 kubelet[1820]: I1027 23:28:23.726170 1820 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 27 23:28:23.726574 kubelet[1820]: I1027 23:28:23.726391 1820 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 27 23:28:23.727274 kubelet[1820]: I1027 23:28:23.726826 1820 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 27 23:28:23.727274 kubelet[1820]: I1027 23:28:23.726878 1820 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 27 23:28:23.764259 kubelet[1820]: I1027 23:28:23.764218 1820 apiserver.go:52] "Watching apiserver" Oct 27 23:28:23.764259 kubelet[1820]: E1027 23:28:23.764256 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:23.778529 kubelet[1820]: I1027 23:28:23.778502 1820 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 27 23:28:23.778805 systemd[1]: Created slice kubepods-burstable-pod1a74a998_9653_4256_afab_65086966668c.slice - libcontainer container kubepods-burstable-pod1a74a998_9653_4256_afab_65086966668c.slice. Oct 27 23:28:23.785513 kubelet[1820]: I1027 23:28:23.785480 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/1a74a998-9653-4256-afab-65086966668c-run\") pod \"kube-flannel-ds-tkv5h\" (UID: \"1a74a998-9653-4256-afab-65086966668c\") " pod="kube-flannel/kube-flannel-ds-tkv5h" Oct 27 23:28:23.785513 kubelet[1820]: I1027 23:28:23.785516 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/1a74a998-9653-4256-afab-65086966668c-cni-plugin\") pod \"kube-flannel-ds-tkv5h\" (UID: \"1a74a998-9653-4256-afab-65086966668c\") " pod="kube-flannel/kube-flannel-ds-tkv5h" Oct 27 23:28:23.785633 kubelet[1820]: I1027 23:28:23.785539 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/1a74a998-9653-4256-afab-65086966668c-flannel-cfg\") pod \"kube-flannel-ds-tkv5h\" (UID: \"1a74a998-9653-4256-afab-65086966668c\") " pod="kube-flannel/kube-flannel-ds-tkv5h" Oct 27 23:28:23.785633 kubelet[1820]: I1027 23:28:23.785564 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/d6edfd77-526c-47fd-a73a-190b75bdcf49-kube-proxy\") pod \"kube-proxy-tmwzd\" (UID: \"d6edfd77-526c-47fd-a73a-190b75bdcf49\") " pod="kube-system/kube-proxy-tmwzd" Oct 27 23:28:23.785633 kubelet[1820]: I1027 23:28:23.785579 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/d6edfd77-526c-47fd-a73a-190b75bdcf49-lib-modules\") pod \"kube-proxy-tmwzd\" (UID: \"d6edfd77-526c-47fd-a73a-190b75bdcf49\") " pod="kube-system/kube-proxy-tmwzd" Oct 27 23:28:23.785633 kubelet[1820]: I1027 23:28:23.785607 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/1a74a998-9653-4256-afab-65086966668c-cni\") pod \"kube-flannel-ds-tkv5h\" (UID: \"1a74a998-9653-4256-afab-65086966668c\") " pod="kube-flannel/kube-flannel-ds-tkv5h" Oct 27 23:28:23.785633 kubelet[1820]: I1027 23:28:23.785624 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/1a74a998-9653-4256-afab-65086966668c-xtables-lock\") pod \"kube-flannel-ds-tkv5h\" (UID: \"1a74a998-9653-4256-afab-65086966668c\") " pod="kube-flannel/kube-flannel-ds-tkv5h" Oct 27 23:28:23.785738 kubelet[1820]: I1027 23:28:23.785640 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6wlvm\" (UniqueName: \"kubernetes.io/projected/1a74a998-9653-4256-afab-65086966668c-kube-api-access-6wlvm\") pod \"kube-flannel-ds-tkv5h\" (UID: \"1a74a998-9653-4256-afab-65086966668c\") " pod="kube-flannel/kube-flannel-ds-tkv5h" Oct 27 23:28:23.785738 kubelet[1820]: I1027 23:28:23.785672 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/d6edfd77-526c-47fd-a73a-190b75bdcf49-xtables-lock\") pod \"kube-proxy-tmwzd\" (UID: \"d6edfd77-526c-47fd-a73a-190b75bdcf49\") " pod="kube-system/kube-proxy-tmwzd" Oct 27 23:28:23.785738 kubelet[1820]: I1027 23:28:23.785703 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kqt9r\" (UniqueName: \"kubernetes.io/projected/d6edfd77-526c-47fd-a73a-190b75bdcf49-kube-api-access-kqt9r\") pod \"kube-proxy-tmwzd\" (UID: \"d6edfd77-526c-47fd-a73a-190b75bdcf49\") " pod="kube-system/kube-proxy-tmwzd" Oct 27 23:28:23.800629 systemd[1]: Created slice kubepods-besteffort-podd6edfd77_526c_47fd_a73a_190b75bdcf49.slice - libcontainer container kubepods-besteffort-podd6edfd77_526c_47fd_a73a_190b75bdcf49.slice. Oct 27 23:28:24.099557 containerd[1518]: time="2025-10-27T23:28:24.099424596Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-tkv5h,Uid:1a74a998-9653-4256-afab-65086966668c,Namespace:kube-flannel,Attempt:0,}" Oct 27 23:28:24.113172 containerd[1518]: time="2025-10-27T23:28:24.113137769Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tmwzd,Uid:d6edfd77-526c-47fd-a73a-190b75bdcf49,Namespace:kube-system,Attempt:0,}" Oct 27 23:28:24.607859 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4155908824.mount: Deactivated successfully. Oct 27 23:28:24.614070 containerd[1518]: time="2025-10-27T23:28:24.614023157Z" 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\"}" Oct 27 23:28:24.615243 containerd[1518]: time="2025-10-27T23:28:24.615210790Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Oct 27 23:28:24.616925 containerd[1518]: time="2025-10-27T23:28:24.616894128Z" 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\"}" Oct 27 23:28:24.621252 containerd[1518]: time="2025-10-27T23:28:24.621201445Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 27 23:28:24.621880 containerd[1518]: time="2025-10-27T23:28:24.621847613Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 27 23:28:24.623557 containerd[1518]: time="2025-10-27T23:28:24.623514122Z" 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\"}" Oct 27 23:28:24.626327 containerd[1518]: time="2025-10-27T23:28:24.625940877Z" 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 509.126571ms" Oct 27 23:28:24.626327 containerd[1518]: time="2025-10-27T23:28:24.626177811Z" 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 521.615888ms" Oct 27 23:28:24.642547 containerd[1518]: time="2025-10-27T23:28:24.642494448Z" level=info msg="connecting to shim ed6dab28cd4782dc13ceaf1f3fa2d1c9ab39692f1c00490ac1a02b2a415ba5f4" address="unix:///run/containerd/s/c642e4a54e74e62cbe4adb27c0946731b6f3c99288b0fefe17bcc4866bab51c4" namespace=k8s.io protocol=ttrpc version=3 Oct 27 23:28:24.644594 containerd[1518]: time="2025-10-27T23:28:24.644553321Z" level=info msg="connecting to shim 0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa" address="unix:///run/containerd/s/6c0273cdbce8573ddd89f6247b3686376230d37da8fc1239e6e620443eb075b3" namespace=k8s.io protocol=ttrpc version=3 Oct 27 23:28:24.662620 systemd[1]: Started cri-containerd-ed6dab28cd4782dc13ceaf1f3fa2d1c9ab39692f1c00490ac1a02b2a415ba5f4.scope - libcontainer container ed6dab28cd4782dc13ceaf1f3fa2d1c9ab39692f1c00490ac1a02b2a415ba5f4. Oct 27 23:28:24.666355 systemd[1]: Started cri-containerd-0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa.scope - libcontainer container 0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa. Oct 27 23:28:24.689932 containerd[1518]: time="2025-10-27T23:28:24.689892491Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tmwzd,Uid:d6edfd77-526c-47fd-a73a-190b75bdcf49,Namespace:kube-system,Attempt:0,} returns sandbox id \"ed6dab28cd4782dc13ceaf1f3fa2d1c9ab39692f1c00490ac1a02b2a415ba5f4\"" Oct 27 23:28:24.692213 containerd[1518]: time="2025-10-27T23:28:24.692002253Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Oct 27 23:28:24.705292 containerd[1518]: time="2025-10-27T23:28:24.705251897Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-tkv5h,Uid:1a74a998-9653-4256-afab-65086966668c,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa\"" Oct 27 23:28:24.764881 kubelet[1820]: E1027 23:28:24.764835 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:25.667634 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1331853817.mount: Deactivated successfully. Oct 27 23:28:25.765786 kubelet[1820]: E1027 23:28:25.765746 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:25.907156 containerd[1518]: time="2025-10-27T23:28:25.907095797Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:25.907835 containerd[1518]: time="2025-10-27T23:28:25.907807324Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240108" Oct 27 23:28:25.908385 containerd[1518]: time="2025-10-27T23:28:25.908362812Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:25.910235 containerd[1518]: time="2025-10-27T23:28:25.910201941Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:25.910994 containerd[1518]: time="2025-10-27T23:28:25.910951166Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.2189175s" Oct 27 23:28:25.910994 containerd[1518]: time="2025-10-27T23:28:25.910986219Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Oct 27 23:28:25.912112 containerd[1518]: time="2025-10-27T23:28:25.912051326Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 27 23:28:25.914781 containerd[1518]: time="2025-10-27T23:28:25.914746884Z" level=info msg="CreateContainer within sandbox \"ed6dab28cd4782dc13ceaf1f3fa2d1c9ab39692f1c00490ac1a02b2a415ba5f4\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 27 23:28:25.924212 containerd[1518]: time="2025-10-27T23:28:25.924112629Z" level=info msg="Container 353c8ddd0ac063e7ae3e84b24bfb6da06c047d6fa2e520e804212b140d41610e: CDI devices from CRI Config.CDIDevices: []" Oct 27 23:28:25.931267 containerd[1518]: time="2025-10-27T23:28:25.931103868Z" level=info msg="CreateContainer within sandbox \"ed6dab28cd4782dc13ceaf1f3fa2d1c9ab39692f1c00490ac1a02b2a415ba5f4\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"353c8ddd0ac063e7ae3e84b24bfb6da06c047d6fa2e520e804212b140d41610e\"" Oct 27 23:28:25.931865 containerd[1518]: time="2025-10-27T23:28:25.931838791Z" level=info msg="StartContainer for \"353c8ddd0ac063e7ae3e84b24bfb6da06c047d6fa2e520e804212b140d41610e\"" Oct 27 23:28:25.934419 containerd[1518]: time="2025-10-27T23:28:25.934093955Z" level=info msg="connecting to shim 353c8ddd0ac063e7ae3e84b24bfb6da06c047d6fa2e520e804212b140d41610e" address="unix:///run/containerd/s/c642e4a54e74e62cbe4adb27c0946731b6f3c99288b0fefe17bcc4866bab51c4" protocol=ttrpc version=3 Oct 27 23:28:25.960644 systemd[1]: Started cri-containerd-353c8ddd0ac063e7ae3e84b24bfb6da06c047d6fa2e520e804212b140d41610e.scope - libcontainer container 353c8ddd0ac063e7ae3e84b24bfb6da06c047d6fa2e520e804212b140d41610e. Oct 27 23:28:25.995356 containerd[1518]: time="2025-10-27T23:28:25.995238872Z" level=info msg="StartContainer for \"353c8ddd0ac063e7ae3e84b24bfb6da06c047d6fa2e520e804212b140d41610e\" returns successfully" Oct 27 23:28:26.766562 kubelet[1820]: E1027 23:28:26.766526 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:26.859821 containerd[1518]: time="2025-10-27T23:28:26.859609161Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:26.860693 containerd[1518]: time="2025-10-27T23:28:26.860370019Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Oct 27 23:28:26.861483 containerd[1518]: time="2025-10-27T23:28:26.861446498Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:26.864417 containerd[1518]: time="2025-10-27T23:28:26.864366401Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:26.865522 containerd[1518]: time="2025-10-27T23:28:26.865489903Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 953.390906ms" Oct 27 23:28:26.865522 containerd[1518]: time="2025-10-27T23:28:26.865521825Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 27 23:28:26.869030 containerd[1518]: time="2025-10-27T23:28:26.868999995Z" level=info msg="CreateContainer within sandbox \"0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 27 23:28:26.874825 containerd[1518]: time="2025-10-27T23:28:26.874768827Z" level=info msg="Container 84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42: CDI devices from CRI Config.CDIDevices: []" Oct 27 23:28:26.879846 containerd[1518]: time="2025-10-27T23:28:26.879809485Z" level=info msg="CreateContainer within sandbox \"0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42\"" Oct 27 23:28:26.880410 containerd[1518]: time="2025-10-27T23:28:26.880380168Z" level=info msg="StartContainer for \"84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42\"" Oct 27 23:28:26.881153 containerd[1518]: time="2025-10-27T23:28:26.881126766Z" level=info msg="connecting to shim 84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42" address="unix:///run/containerd/s/6c0273cdbce8573ddd89f6247b3686376230d37da8fc1239e6e620443eb075b3" protocol=ttrpc version=3 Oct 27 23:28:26.906568 systemd[1]: Started cri-containerd-84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42.scope - libcontainer container 84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42. Oct 27 23:28:26.923218 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2735078880.mount: Deactivated successfully. Oct 27 23:28:26.943702 systemd[1]: cri-containerd-84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42.scope: Deactivated successfully. Oct 27 23:28:26.945561 containerd[1518]: time="2025-10-27T23:28:26.945347094Z" level=info msg="StartContainer for \"84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42\" returns successfully" Oct 27 23:28:26.946758 containerd[1518]: time="2025-10-27T23:28:26.946652079Z" level=info msg="received exit event container_id:\"84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42\" id:\"84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42\" pid:2156 exited_at:{seconds:1761607706 nanos:945454958}" Oct 27 23:28:26.946758 containerd[1518]: time="2025-10-27T23:28:26.946742359Z" level=info msg="TaskExit event in podsandbox handler container_id:\"84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42\" id:\"84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42\" pid:2156 exited_at:{seconds:1761607706 nanos:945454958}" Oct 27 23:28:26.962720 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-84198de960fe7a94c182434cecc0e6222bc342c03eed7e636ddb3defe1fe1f42-rootfs.mount: Deactivated successfully. Oct 27 23:28:27.766818 kubelet[1820]: E1027 23:28:27.766772 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:27.928806 containerd[1518]: time="2025-10-27T23:28:27.928274683Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 27 23:28:27.938717 kubelet[1820]: I1027 23:28:27.938646 1820 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-tmwzd" podStartSLOduration=3.718551182 podStartE2EDuration="4.938629637s" podCreationTimestamp="2025-10-27 23:28:23 +0000 UTC" firstStartedPulling="2025-10-27 23:28:24.6917428 +0000 UTC m=+3.081000135" lastFinishedPulling="2025-10-27 23:28:25.911821255 +0000 UTC m=+4.301078590" observedRunningTime="2025-10-27 23:28:26.938422478 +0000 UTC m=+5.327679813" watchObservedRunningTime="2025-10-27 23:28:27.938629637 +0000 UTC m=+6.327886932" Oct 27 23:28:28.767375 kubelet[1820]: E1027 23:28:28.767328 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:29.365011 containerd[1518]: time="2025-10-27T23:28:29.364958511Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:29.366237 containerd[1518]: time="2025-10-27T23:28:29.366160709Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Oct 27 23:28:29.367425 containerd[1518]: time="2025-10-27T23:28:29.366946132Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:29.371434 containerd[1518]: time="2025-10-27T23:28:29.371351759Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:29.372855 containerd[1518]: time="2025-10-27T23:28:29.372815311Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 1.444500063s" Oct 27 23:28:29.372855 containerd[1518]: time="2025-10-27T23:28:29.372847499Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 27 23:28:29.377994 containerd[1518]: time="2025-10-27T23:28:29.377549392Z" level=info msg="CreateContainer within sandbox \"0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 27 23:28:29.384957 containerd[1518]: time="2025-10-27T23:28:29.384902780Z" level=info msg="Container 3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b: CDI devices from CRI Config.CDIDevices: []" Oct 27 23:28:29.390861 containerd[1518]: time="2025-10-27T23:28:29.390802706Z" level=info msg="CreateContainer within sandbox \"0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b\"" Oct 27 23:28:29.392304 containerd[1518]: time="2025-10-27T23:28:29.391273488Z" level=info msg="StartContainer for \"3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b\"" Oct 27 23:28:29.392304 containerd[1518]: time="2025-10-27T23:28:29.392110758Z" level=info msg="connecting to shim 3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b" address="unix:///run/containerd/s/6c0273cdbce8573ddd89f6247b3686376230d37da8fc1239e6e620443eb075b3" protocol=ttrpc version=3 Oct 27 23:28:29.417588 systemd[1]: Started cri-containerd-3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b.scope - libcontainer container 3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b. Oct 27 23:28:29.440005 systemd[1]: cri-containerd-3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b.scope: Deactivated successfully. Oct 27 23:28:29.440596 containerd[1518]: time="2025-10-27T23:28:29.440504436Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b\" id:\"3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b\" pid:2230 exited_at:{seconds:1761607709 nanos:440203806}" Oct 27 23:28:29.440800 containerd[1518]: time="2025-10-27T23:28:29.440580584Z" level=info msg="received exit event container_id:\"3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b\" id:\"3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b\" pid:2230 exited_at:{seconds:1761607709 nanos:440203806}" Oct 27 23:28:29.441380 containerd[1518]: time="2025-10-27T23:28:29.441355318Z" level=info msg="StartContainer for \"3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b\" returns successfully" Oct 27 23:28:29.462023 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3e6efead97863280aa906441482a9cea21c74e691434ebf45471b4c884a5bb4b-rootfs.mount: Deactivated successfully. Oct 27 23:28:29.475101 kubelet[1820]: I1027 23:28:29.475072 1820 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 27 23:28:29.767901 kubelet[1820]: E1027 23:28:29.767817 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:29.934606 containerd[1518]: time="2025-10-27T23:28:29.934557835Z" level=info msg="CreateContainer within sandbox \"0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 27 23:28:29.943686 containerd[1518]: time="2025-10-27T23:28:29.943648139Z" level=info msg="Container 073b9427249e59587d556a71eac5c11a34757422287288c20bd5ea33f25b9662: CDI devices from CRI Config.CDIDevices: []" Oct 27 23:28:29.950345 containerd[1518]: time="2025-10-27T23:28:29.950285326Z" level=info msg="CreateContainer within sandbox \"0971cf9a318b01d458a6d45642a9e91232e69e865d03516fced6358523bce3aa\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"073b9427249e59587d556a71eac5c11a34757422287288c20bd5ea33f25b9662\"" Oct 27 23:28:29.950865 containerd[1518]: time="2025-10-27T23:28:29.950840783Z" level=info msg="StartContainer for \"073b9427249e59587d556a71eac5c11a34757422287288c20bd5ea33f25b9662\"" Oct 27 23:28:29.951777 containerd[1518]: time="2025-10-27T23:28:29.951750518Z" level=info msg="connecting to shim 073b9427249e59587d556a71eac5c11a34757422287288c20bd5ea33f25b9662" address="unix:///run/containerd/s/6c0273cdbce8573ddd89f6247b3686376230d37da8fc1239e6e620443eb075b3" protocol=ttrpc version=3 Oct 27 23:28:29.974633 systemd[1]: Started cri-containerd-073b9427249e59587d556a71eac5c11a34757422287288c20bd5ea33f25b9662.scope - libcontainer container 073b9427249e59587d556a71eac5c11a34757422287288c20bd5ea33f25b9662. Oct 27 23:28:30.007275 containerd[1518]: time="2025-10-27T23:28:30.007226818Z" level=info msg="StartContainer for \"073b9427249e59587d556a71eac5c11a34757422287288c20bd5ea33f25b9662\" returns successfully" Oct 27 23:28:30.768524 kubelet[1820]: E1027 23:28:30.768460 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:30.948363 kubelet[1820]: I1027 23:28:30.948295 1820 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-tkv5h" podStartSLOduration=3.280154149 podStartE2EDuration="7.94827779s" podCreationTimestamp="2025-10-27 23:28:23 +0000 UTC" firstStartedPulling="2025-10-27 23:28:24.706328256 +0000 UTC m=+3.095585591" lastFinishedPulling="2025-10-27 23:28:29.374451897 +0000 UTC m=+7.763709232" observedRunningTime="2025-10-27 23:28:30.948173268 +0000 UTC m=+9.337430603" watchObservedRunningTime="2025-10-27 23:28:30.94827779 +0000 UTC m=+9.337535125" Oct 27 23:28:31.072729 systemd-networkd[1427]: flannel.1: Link UP Oct 27 23:28:31.072736 systemd-networkd[1427]: flannel.1: Gained carrier Oct 27 23:28:31.768890 kubelet[1820]: E1027 23:28:31.768801 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:32.293661 systemd-networkd[1427]: flannel.1: Gained IPv6LL Oct 27 23:28:32.769801 kubelet[1820]: E1027 23:28:32.769738 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:33.770652 kubelet[1820]: E1027 23:28:33.770583 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:34.094208 systemd[1]: Created slice kubepods-besteffort-pod62115822_83b4_446c_a642_72acfd3c324c.slice - libcontainer container kubepods-besteffort-pod62115822_83b4_446c_a642_72acfd3c324c.slice. Oct 27 23:28:34.158718 kubelet[1820]: I1027 23:28:34.158656 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hvs8r\" (UniqueName: \"kubernetes.io/projected/62115822-83b4-446c-a642-72acfd3c324c-kube-api-access-hvs8r\") pod \"nginx-deployment-7fcdb87857-4ml2q\" (UID: \"62115822-83b4-446c-a642-72acfd3c324c\") " pod="default/nginx-deployment-7fcdb87857-4ml2q" Oct 27 23:28:34.399747 containerd[1518]: time="2025-10-27T23:28:34.399642327Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-4ml2q,Uid:62115822-83b4-446c-a642-72acfd3c324c,Namespace:default,Attempt:0,}" Oct 27 23:28:34.428895 systemd-networkd[1427]: cni0: Link UP Oct 27 23:28:34.439355 systemd-networkd[1427]: veth882a20ad: Link UP Oct 27 23:28:34.442809 kernel: cni0: port 1(veth882a20ad) entered blocking state Oct 27 23:28:34.446796 kernel: cni0: port 1(veth882a20ad) entered disabled state Oct 27 23:28:34.446891 kernel: veth882a20ad: entered allmulticast mode Oct 27 23:28:34.447941 kernel: veth882a20ad: entered promiscuous mode Oct 27 23:28:34.458532 kernel: cni0: port 1(veth882a20ad) entered blocking state Oct 27 23:28:34.458626 kernel: cni0: port 1(veth882a20ad) entered forwarding state Oct 27 23:28:34.458940 systemd-networkd[1427]: veth882a20ad: Gained carrier Oct 27 23:28:34.459553 systemd-networkd[1427]: cni0: Gained carrier Oct 27 23:28:34.462770 containerd[1518]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 27 23:28:34.462770 containerd[1518]: delegateAdd: netconf sent to delegate plugin: Oct 27 23:28:34.504590 containerd[1518]: {"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-10-27T23:28:34.504545354Z" level=info msg="connecting to shim 527ed55d734471fd999f55292f927e644d072454db4cdc32e0d0c38e52adecbd" address="unix:///run/containerd/s/dd2fbd572047eea9aa903bca31466a7355b0a838e6d8759ea460300c2c9550e6" namespace=k8s.io protocol=ttrpc version=3 Oct 27 23:28:34.534622 systemd[1]: Started cri-containerd-527ed55d734471fd999f55292f927e644d072454db4cdc32e0d0c38e52adecbd.scope - libcontainer container 527ed55d734471fd999f55292f927e644d072454db4cdc32e0d0c38e52adecbd. Oct 27 23:28:34.550751 systemd-resolved[1359]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 27 23:28:34.570933 containerd[1518]: time="2025-10-27T23:28:34.570891216Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-4ml2q,Uid:62115822-83b4-446c-a642-72acfd3c324c,Namespace:default,Attempt:0,} returns sandbox id \"527ed55d734471fd999f55292f927e644d072454db4cdc32e0d0c38e52adecbd\"" Oct 27 23:28:34.572362 containerd[1518]: time="2025-10-27T23:28:34.572312629Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 27 23:28:34.771100 kubelet[1820]: E1027 23:28:34.771025 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:35.771725 kubelet[1820]: E1027 23:28:35.771647 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:36.261554 systemd-networkd[1427]: veth882a20ad: Gained IPv6LL Oct 27 23:28:36.262633 systemd-networkd[1427]: cni0: Gained IPv6LL Oct 27 23:28:36.371274 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3393535389.mount: Deactivated successfully. Oct 27 23:28:36.773209 kubelet[1820]: E1027 23:28:36.773170 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:37.195238 containerd[1518]: time="2025-10-27T23:28:37.195182038Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:37.196437 containerd[1518]: time="2025-10-27T23:28:37.196316667Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Oct 27 23:28:37.197332 containerd[1518]: time="2025-10-27T23:28:37.197297009Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:37.200028 containerd[1518]: time="2025-10-27T23:28:37.199972954Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:37.201423 containerd[1518]: time="2025-10-27T23:28:37.200828377Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 2.628482774s" Oct 27 23:28:37.201541 containerd[1518]: time="2025-10-27T23:28:37.201519630Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 27 23:28:37.204933 containerd[1518]: time="2025-10-27T23:28:37.204867901Z" level=info msg="CreateContainer within sandbox \"527ed55d734471fd999f55292f927e644d072454db4cdc32e0d0c38e52adecbd\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 27 23:28:37.213386 containerd[1518]: time="2025-10-27T23:28:37.212809348Z" level=info msg="Container 137372f9ee72694d4398c550bdd4bd73687d2a9ecb581ac5fb148b8c67088d8c: CDI devices from CRI Config.CDIDevices: []" Oct 27 23:28:37.218619 containerd[1518]: time="2025-10-27T23:28:37.218577604Z" level=info msg="CreateContainer within sandbox \"527ed55d734471fd999f55292f927e644d072454db4cdc32e0d0c38e52adecbd\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"137372f9ee72694d4398c550bdd4bd73687d2a9ecb581ac5fb148b8c67088d8c\"" Oct 27 23:28:37.219275 containerd[1518]: time="2025-10-27T23:28:37.219251972Z" level=info msg="StartContainer for \"137372f9ee72694d4398c550bdd4bd73687d2a9ecb581ac5fb148b8c67088d8c\"" Oct 27 23:28:37.220111 containerd[1518]: time="2025-10-27T23:28:37.220085789Z" level=info msg="connecting to shim 137372f9ee72694d4398c550bdd4bd73687d2a9ecb581ac5fb148b8c67088d8c" address="unix:///run/containerd/s/dd2fbd572047eea9aa903bca31466a7355b0a838e6d8759ea460300c2c9550e6" protocol=ttrpc version=3 Oct 27 23:28:37.240577 systemd[1]: Started cri-containerd-137372f9ee72694d4398c550bdd4bd73687d2a9ecb581ac5fb148b8c67088d8c.scope - libcontainer container 137372f9ee72694d4398c550bdd4bd73687d2a9ecb581ac5fb148b8c67088d8c. Oct 27 23:28:37.264281 containerd[1518]: time="2025-10-27T23:28:37.264247872Z" level=info msg="StartContainer for \"137372f9ee72694d4398c550bdd4bd73687d2a9ecb581ac5fb148b8c67088d8c\" returns successfully" Oct 27 23:28:37.773897 kubelet[1820]: E1027 23:28:37.773847 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:37.958566 kubelet[1820]: I1027 23:28:37.958502 1820 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-4ml2q" podStartSLOduration=1.328207723 podStartE2EDuration="3.95848584s" podCreationTimestamp="2025-10-27 23:28:34 +0000 UTC" firstStartedPulling="2025-10-27 23:28:34.571873988 +0000 UTC m=+12.961131323" lastFinishedPulling="2025-10-27 23:28:37.202152105 +0000 UTC m=+15.591409440" observedRunningTime="2025-10-27 23:28:37.95783772 +0000 UTC m=+16.347095055" watchObservedRunningTime="2025-10-27 23:28:37.95848584 +0000 UTC m=+16.347743175" Oct 27 23:28:38.774490 kubelet[1820]: E1027 23:28:38.774434 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:39.778652 kubelet[1820]: E1027 23:28:39.774718 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:40.554513 systemd[1]: Created slice kubepods-besteffort-pod5ef331d0_60b6_484d_bd03_8c5543828caf.slice - libcontainer container kubepods-besteffort-pod5ef331d0_60b6_484d_bd03_8c5543828caf.slice. Oct 27 23:28:40.597035 kubelet[1820]: I1027 23:28:40.596974 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ss5j5\" (UniqueName: \"kubernetes.io/projected/5ef331d0-60b6-484d-bd03-8c5543828caf-kube-api-access-ss5j5\") pod \"nfs-server-provisioner-0\" (UID: \"5ef331d0-60b6-484d-bd03-8c5543828caf\") " pod="default/nfs-server-provisioner-0" Oct 27 23:28:40.597035 kubelet[1820]: I1027 23:28:40.597035 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/5ef331d0-60b6-484d-bd03-8c5543828caf-data\") pod \"nfs-server-provisioner-0\" (UID: \"5ef331d0-60b6-484d-bd03-8c5543828caf\") " pod="default/nfs-server-provisioner-0" Oct 27 23:28:40.775025 kubelet[1820]: E1027 23:28:40.774958 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:40.857376 containerd[1518]: time="2025-10-27T23:28:40.857064166Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:5ef331d0-60b6-484d-bd03-8c5543828caf,Namespace:default,Attempt:0,}" Oct 27 23:28:40.870470 systemd-networkd[1427]: veth1f2db5f9: Link UP Oct 27 23:28:40.871975 kernel: cni0: port 2(veth1f2db5f9) entered blocking state Oct 27 23:28:40.872068 kernel: cni0: port 2(veth1f2db5f9) entered disabled state Oct 27 23:28:40.872864 kernel: veth1f2db5f9: entered allmulticast mode Oct 27 23:28:40.873667 kernel: veth1f2db5f9: entered promiscuous mode Oct 27 23:28:40.878552 kernel: cni0: port 2(veth1f2db5f9) entered blocking state Oct 27 23:28:40.878620 kernel: cni0: port 2(veth1f2db5f9) entered forwarding state Oct 27 23:28:40.884044 systemd-networkd[1427]: veth1f2db5f9: Gained carrier Oct 27 23:28:40.885522 containerd[1518]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40001847f0), "name":"cbr0", "type":"bridge"} Oct 27 23:28:40.885522 containerd[1518]: delegateAdd: netconf sent to delegate plugin: Oct 27 23:28:40.914972 containerd[1518]: {"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-10-27T23:28:40.914913785Z" level=info msg="connecting to shim c89494af435a6243bb330c0eedc208f9e4f4de97648ea9d260148b2b92526919" address="unix:///run/containerd/s/9720d261c3cb97c66e9d2a72dabe31f7ad672b0b15031a7487840d606238aefa" namespace=k8s.io protocol=ttrpc version=3 Oct 27 23:28:40.935593 systemd[1]: Started cri-containerd-c89494af435a6243bb330c0eedc208f9e4f4de97648ea9d260148b2b92526919.scope - libcontainer container c89494af435a6243bb330c0eedc208f9e4f4de97648ea9d260148b2b92526919. Oct 27 23:28:40.946417 systemd-resolved[1359]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 27 23:28:40.968342 containerd[1518]: time="2025-10-27T23:28:40.968294482Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:5ef331d0-60b6-484d-bd03-8c5543828caf,Namespace:default,Attempt:0,} returns sandbox id \"c89494af435a6243bb330c0eedc208f9e4f4de97648ea9d260148b2b92526919\"" Oct 27 23:28:40.970215 containerd[1518]: time="2025-10-27T23:28:40.969984070Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 27 23:28:41.775371 kubelet[1820]: E1027 23:28:41.775307 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:42.755263 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1002703357.mount: Deactivated successfully. Oct 27 23:28:42.763721 kubelet[1820]: E1027 23:28:42.763685 1820 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:42.776042 kubelet[1820]: E1027 23:28:42.776004 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:42.917560 systemd-networkd[1427]: veth1f2db5f9: Gained IPv6LL Oct 27 23:28:43.776475 kubelet[1820]: E1027 23:28:43.776428 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:44.244081 containerd[1518]: time="2025-10-27T23:28:44.244034807Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:44.245430 containerd[1518]: time="2025-10-27T23:28:44.244415613Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Oct 27 23:28:44.245430 containerd[1518]: time="2025-10-27T23:28:44.245290239Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:44.248688 containerd[1518]: time="2025-10-27T23:28:44.248647645Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:44.249563 containerd[1518]: time="2025-10-27T23:28:44.249533672Z" 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.279510874s" Oct 27 23:28:44.249563 containerd[1518]: time="2025-10-27T23:28:44.249561556Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 27 23:28:44.253077 containerd[1518]: time="2025-10-27T23:28:44.253034256Z" level=info msg="CreateContainer within sandbox \"c89494af435a6243bb330c0eedc208f9e4f4de97648ea9d260148b2b92526919\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 27 23:28:44.260518 containerd[1518]: time="2025-10-27T23:28:44.259674939Z" level=info msg="Container 079c3af0c60177feeab21db89582ce088e1e1950cbca021542691e1226563e79: CDI devices from CRI Config.CDIDevices: []" Oct 27 23:28:44.262979 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1159565806.mount: Deactivated successfully. Oct 27 23:28:44.268345 containerd[1518]: time="2025-10-27T23:28:44.268280220Z" level=info msg="CreateContainer within sandbox \"c89494af435a6243bb330c0eedc208f9e4f4de97648ea9d260148b2b92526919\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"079c3af0c60177feeab21db89582ce088e1e1950cbca021542691e1226563e79\"" Oct 27 23:28:44.268845 containerd[1518]: time="2025-10-27T23:28:44.268807684Z" level=info msg="StartContainer for \"079c3af0c60177feeab21db89582ce088e1e1950cbca021542691e1226563e79\"" Oct 27 23:28:44.269904 containerd[1518]: time="2025-10-27T23:28:44.269860812Z" level=info msg="connecting to shim 079c3af0c60177feeab21db89582ce088e1e1950cbca021542691e1226563e79" address="unix:///run/containerd/s/9720d261c3cb97c66e9d2a72dabe31f7ad672b0b15031a7487840d606238aefa" protocol=ttrpc version=3 Oct 27 23:28:44.292581 systemd[1]: Started cri-containerd-079c3af0c60177feeab21db89582ce088e1e1950cbca021542691e1226563e79.scope - libcontainer container 079c3af0c60177feeab21db89582ce088e1e1950cbca021542691e1226563e79. Oct 27 23:28:44.323440 containerd[1518]: time="2025-10-27T23:28:44.321024922Z" level=info msg="StartContainer for \"079c3af0c60177feeab21db89582ce088e1e1950cbca021542691e1226563e79\" returns successfully" Oct 27 23:28:44.776634 kubelet[1820]: E1027 23:28:44.776571 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:44.974370 kubelet[1820]: I1027 23:28:44.974239 1820 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.6935122489999999 podStartE2EDuration="4.974224139s" podCreationTimestamp="2025-10-27 23:28:40 +0000 UTC" firstStartedPulling="2025-10-27 23:28:40.969657003 +0000 UTC m=+19.358914298" lastFinishedPulling="2025-10-27 23:28:44.250368853 +0000 UTC m=+22.639626188" observedRunningTime="2025-10-27 23:28:44.973989189 +0000 UTC m=+23.363246524" watchObservedRunningTime="2025-10-27 23:28:44.974224139 +0000 UTC m=+23.363481434" Oct 27 23:28:45.777750 kubelet[1820]: E1027 23:28:45.777701 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:46.778131 kubelet[1820]: E1027 23:28:46.778078 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:47.778600 kubelet[1820]: E1027 23:28:47.778556 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:48.779703 kubelet[1820]: E1027 23:28:48.779650 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:49.575634 systemd[1]: Created slice kubepods-besteffort-podb5f8a6a0_0557_4e14_941b_0683341b0738.slice - libcontainer container kubepods-besteffort-podb5f8a6a0_0557_4e14_941b_0683341b0738.slice. Oct 27 23:28:49.651478 kubelet[1820]: I1027 23:28:49.651435 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-82aac478-437d-4293-bedf-4a469a5a3ce7\" (UniqueName: \"kubernetes.io/nfs/b5f8a6a0-0557-4e14-941b-0683341b0738-pvc-82aac478-437d-4293-bedf-4a469a5a3ce7\") pod \"test-pod-1\" (UID: \"b5f8a6a0-0557-4e14-941b-0683341b0738\") " pod="default/test-pod-1" Oct 27 23:28:49.651478 kubelet[1820]: I1027 23:28:49.651486 1820 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h77x6\" (UniqueName: \"kubernetes.io/projected/b5f8a6a0-0557-4e14-941b-0683341b0738-kube-api-access-h77x6\") pod \"test-pod-1\" (UID: \"b5f8a6a0-0557-4e14-941b-0683341b0738\") " pod="default/test-pod-1" Oct 27 23:28:49.775433 kernel: netfs: FS-Cache loaded Oct 27 23:28:49.780698 kubelet[1820]: E1027 23:28:49.780657 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:49.800685 kernel: RPC: Registered named UNIX socket transport module. Oct 27 23:28:49.800805 kernel: RPC: Registered udp transport module. Oct 27 23:28:49.800825 kernel: RPC: Registered tcp transport module. Oct 27 23:28:49.802043 kernel: RPC: Registered tcp-with-tls transport module. Oct 27 23:28:49.802082 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 27 23:28:49.979114 kernel: NFS: Registering the id_resolver key type Oct 27 23:28:49.979269 kernel: Key type id_resolver registered Oct 27 23:28:49.979290 kernel: Key type id_legacy registered Oct 27 23:28:50.002331 nfsidmap[2797]: 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 Oct 27 23:28:50.004613 nfsidmap[2797]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 27 23:28:50.006571 nfsidmap[2798]: 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 Oct 27 23:28:50.006965 nfsidmap[2798]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 27 23:28:50.020837 nfsrahead[2801]: setting /var/lib/kubelet/pods/b5f8a6a0-0557-4e14-941b-0683341b0738/volumes/kubernetes.io~nfs/pvc-82aac478-437d-4293-bedf-4a469a5a3ce7 readahead to 128 Oct 27 23:28:50.179609 containerd[1518]: time="2025-10-27T23:28:50.179550001Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b5f8a6a0-0557-4e14-941b-0683341b0738,Namespace:default,Attempt:0,}" Oct 27 23:28:50.192339 systemd-networkd[1427]: vetha8ae212a: Link UP Oct 27 23:28:50.194890 kernel: cni0: port 3(vetha8ae212a) entered blocking state Oct 27 23:28:50.194978 kernel: cni0: port 3(vetha8ae212a) entered disabled state Oct 27 23:28:50.195715 kernel: vetha8ae212a: entered allmulticast mode Oct 27 23:28:50.198046 kernel: vetha8ae212a: entered promiscuous mode Oct 27 23:28:50.205448 kernel: cni0: port 3(vetha8ae212a) entered blocking state Oct 27 23:28:50.205708 kernel: cni0: port 3(vetha8ae212a) entered forwarding state Oct 27 23:28:50.205484 systemd-networkd[1427]: vetha8ae212a: Gained carrier Oct 27 23:28:50.207887 containerd[1518]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400009a950), "name":"cbr0", "type":"bridge"} Oct 27 23:28:50.207887 containerd[1518]: delegateAdd: netconf sent to delegate plugin: Oct 27 23:28:50.228849 containerd[1518]: {"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-10-27T23:28:50.228800990Z" level=info msg="connecting to shim 5ba7ff095c7d2f0ff1149124670e0afbfc05f78b3a8bc2e647c0f016a555ed53" address="unix:///run/containerd/s/600cb7d255c84167b0f95029c2e8b33e93314166554657acdc2ffd035a061f67" namespace=k8s.io protocol=ttrpc version=3 Oct 27 23:28:50.251585 systemd[1]: Started cri-containerd-5ba7ff095c7d2f0ff1149124670e0afbfc05f78b3a8bc2e647c0f016a555ed53.scope - libcontainer container 5ba7ff095c7d2f0ff1149124670e0afbfc05f78b3a8bc2e647c0f016a555ed53. Oct 27 23:28:50.262749 systemd-resolved[1359]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 27 23:28:50.284100 containerd[1518]: time="2025-10-27T23:28:50.284020793Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b5f8a6a0-0557-4e14-941b-0683341b0738,Namespace:default,Attempt:0,} returns sandbox id \"5ba7ff095c7d2f0ff1149124670e0afbfc05f78b3a8bc2e647c0f016a555ed53\"" Oct 27 23:28:50.285244 containerd[1518]: time="2025-10-27T23:28:50.285212659Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 27 23:28:50.534364 containerd[1518]: time="2025-10-27T23:28:50.534206320Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 27 23:28:50.535098 containerd[1518]: time="2025-10-27T23:28:50.535066728Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 27 23:28:50.537984 containerd[1518]: time="2025-10-27T23:28:50.537936168Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 252.687546ms" Oct 27 23:28:50.537984 containerd[1518]: time="2025-10-27T23:28:50.537978570Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 27 23:28:50.542513 containerd[1518]: time="2025-10-27T23:28:50.541868867Z" level=info msg="CreateContainer within sandbox \"5ba7ff095c7d2f0ff1149124670e0afbfc05f78b3a8bc2e647c0f016a555ed53\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 27 23:28:50.552642 containerd[1518]: time="2025-10-27T23:28:50.552602147Z" level=info msg="Container 72a6cb2b57f1a031cd09b54812e11af84a63e4d0138fd70fc01a7631d766d4d1: CDI devices from CRI Config.CDIDevices: []" Oct 27 23:28:50.562841 containerd[1518]: time="2025-10-27T23:28:50.562736352Z" level=info msg="CreateContainer within sandbox \"5ba7ff095c7d2f0ff1149124670e0afbfc05f78b3a8bc2e647c0f016a555ed53\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"72a6cb2b57f1a031cd09b54812e11af84a63e4d0138fd70fc01a7631d766d4d1\"" Oct 27 23:28:50.563560 containerd[1518]: time="2025-10-27T23:28:50.563531477Z" level=info msg="StartContainer for \"72a6cb2b57f1a031cd09b54812e11af84a63e4d0138fd70fc01a7631d766d4d1\"" Oct 27 23:28:50.564502 containerd[1518]: time="2025-10-27T23:28:50.564477370Z" level=info msg="connecting to shim 72a6cb2b57f1a031cd09b54812e11af84a63e4d0138fd70fc01a7631d766d4d1" address="unix:///run/containerd/s/600cb7d255c84167b0f95029c2e8b33e93314166554657acdc2ffd035a061f67" protocol=ttrpc version=3 Oct 27 23:28:50.589677 systemd[1]: Started cri-containerd-72a6cb2b57f1a031cd09b54812e11af84a63e4d0138fd70fc01a7631d766d4d1.scope - libcontainer container 72a6cb2b57f1a031cd09b54812e11af84a63e4d0138fd70fc01a7631d766d4d1. Oct 27 23:28:50.629183 containerd[1518]: time="2025-10-27T23:28:50.629138859Z" level=info msg="StartContainer for \"72a6cb2b57f1a031cd09b54812e11af84a63e4d0138fd70fc01a7631d766d4d1\" returns successfully" Oct 27 23:28:50.781856 kubelet[1820]: E1027 23:28:50.781270 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:50.990730 kubelet[1820]: I1027 23:28:50.990655 1820 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=10.736824072 podStartE2EDuration="10.990637641s" podCreationTimestamp="2025-10-27 23:28:40 +0000 UTC" firstStartedPulling="2025-10-27 23:28:50.284936604 +0000 UTC m=+28.674193899" lastFinishedPulling="2025-10-27 23:28:50.538750133 +0000 UTC m=+28.928007468" observedRunningTime="2025-10-27 23:28:50.990494153 +0000 UTC m=+29.379751488" watchObservedRunningTime="2025-10-27 23:28:50.990637641 +0000 UTC m=+29.379894936" Oct 27 23:28:51.749600 systemd-networkd[1427]: vetha8ae212a: Gained IPv6LL Oct 27 23:28:51.782364 kubelet[1820]: E1027 23:28:51.782294 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 27 23:28:52.783012 kubelet[1820]: E1027 23:28:52.782968 1820 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"