Oct 30 23:47:33.806778 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 30 23:47:33.806805 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 Thu Oct 30 22:10:35 -00 2025 Oct 30 23:47:33.806816 kernel: KASLR enabled Oct 30 23:47:33.806821 kernel: efi: EFI v2.7 by EDK II Oct 30 23:47:33.806827 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Oct 30 23:47:33.806833 kernel: random: crng init done Oct 30 23:47:33.806840 kernel: secureboot: Secure boot disabled Oct 30 23:47:33.806845 kernel: ACPI: Early table checksum verification disabled Oct 30 23:47:33.806854 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 30 23:47:33.806861 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 30 23:47:33.806867 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806873 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806881 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806889 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806898 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806906 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806912 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806918 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806925 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 23:47:33.806931 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 30 23:47:33.806937 kernel: ACPI: Use ACPI SPCR as default console: No Oct 30 23:47:33.806944 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 23:47:33.806952 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Oct 30 23:47:33.806958 kernel: Zone ranges: Oct 30 23:47:33.806964 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 23:47:33.806972 kernel: DMA32 empty Oct 30 23:47:33.806979 kernel: Normal empty Oct 30 23:47:33.806986 kernel: Device empty Oct 30 23:47:33.806993 kernel: Movable zone start for each node Oct 30 23:47:33.806999 kernel: Early memory node ranges Oct 30 23:47:33.807006 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Oct 30 23:47:33.807012 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Oct 30 23:47:33.807021 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Oct 30 23:47:33.807030 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 30 23:47:33.807036 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 30 23:47:33.807042 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 30 23:47:33.807048 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 30 23:47:33.807056 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 30 23:47:33.807062 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 30 23:47:33.807068 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 30 23:47:33.807076 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 30 23:47:33.807083 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 30 23:47:33.807089 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 30 23:47:33.807097 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 23:47:33.807103 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 30 23:47:33.807110 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Oct 30 23:47:33.807117 kernel: psci: probing for conduit method from ACPI. Oct 30 23:47:33.807123 kernel: psci: PSCIv1.1 detected in firmware. Oct 30 23:47:33.807129 kernel: psci: Using standard PSCI v0.2 function IDs Oct 30 23:47:33.807136 kernel: psci: Trusted OS migration not required Oct 30 23:47:33.807142 kernel: psci: SMC Calling Convention v1.1 Oct 30 23:47:33.807149 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 30 23:47:33.807155 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 30 23:47:33.807163 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 30 23:47:33.807169 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 30 23:47:33.807176 kernel: Detected PIPT I-cache on CPU0 Oct 30 23:47:33.807182 kernel: CPU features: detected: GIC system register CPU interface Oct 30 23:47:33.807190 kernel: CPU features: detected: Spectre-v4 Oct 30 23:47:33.807197 kernel: CPU features: detected: Spectre-BHB Oct 30 23:47:33.807203 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 30 23:47:33.807210 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 30 23:47:33.807217 kernel: CPU features: detected: ARM erratum 1418040 Oct 30 23:47:33.807223 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 30 23:47:33.807230 kernel: alternatives: applying boot alternatives Oct 30 23:47:33.807237 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=fe9a0b97d6cc3ae4bb51413f0dcf8829730d06a1c56255949a7891220815365c Oct 30 23:47:33.807245 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 30 23:47:33.807252 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 30 23:47:33.807258 kernel: Fallback order for Node 0: 0 Oct 30 23:47:33.808871 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 30 23:47:33.808878 kernel: Policy zone: DMA Oct 30 23:47:33.808884 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 30 23:47:33.808891 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 30 23:47:33.808905 kernel: software IO TLB: area num 4. Oct 30 23:47:33.808912 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 30 23:47:33.808918 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Oct 30 23:47:33.808925 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 30 23:47:33.808934 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 30 23:47:33.808941 kernel: rcu: RCU event tracing is enabled. Oct 30 23:47:33.808947 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 30 23:47:33.808954 kernel: Trampoline variant of Tasks RCU enabled. Oct 30 23:47:33.808960 kernel: Tracing variant of Tasks RCU enabled. Oct 30 23:47:33.808967 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 30 23:47:33.808973 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 30 23:47:33.808980 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 30 23:47:33.808986 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 30 23:47:33.808993 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 30 23:47:33.808999 kernel: GICv3: 256 SPIs implemented Oct 30 23:47:33.809007 kernel: GICv3: 0 Extended SPIs implemented Oct 30 23:47:33.809013 kernel: Root IRQ handler: gic_handle_irq Oct 30 23:47:33.809020 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 30 23:47:33.809026 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 30 23:47:33.809032 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 30 23:47:33.809038 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 30 23:47:33.809045 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 30 23:47:33.809052 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 30 23:47:33.809058 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 30 23:47:33.809065 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 30 23:47:33.809071 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 30 23:47:33.809078 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 23:47:33.809086 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 30 23:47:33.809093 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 30 23:47:33.809100 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 30 23:47:33.809106 kernel: arm-pv: using stolen time PV Oct 30 23:47:33.809113 kernel: Console: colour dummy device 80x25 Oct 30 23:47:33.809120 kernel: ACPI: Core revision 20240827 Oct 30 23:47:33.809127 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 30 23:47:33.809133 kernel: pid_max: default: 32768 minimum: 301 Oct 30 23:47:33.809140 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 30 23:47:33.809147 kernel: landlock: Up and running. Oct 30 23:47:33.809155 kernel: SELinux: Initializing. Oct 30 23:47:33.809162 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 23:47:33.809169 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 23:47:33.809175 kernel: rcu: Hierarchical SRCU implementation. Oct 30 23:47:33.809183 kernel: rcu: Max phase no-delay instances is 400. Oct 30 23:47:33.809190 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 30 23:47:33.809197 kernel: Remapping and enabling EFI services. Oct 30 23:47:33.809204 kernel: smp: Bringing up secondary CPUs ... Oct 30 23:47:33.809210 kernel: Detected PIPT I-cache on CPU1 Oct 30 23:47:33.809224 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 30 23:47:33.809230 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 30 23:47:33.809238 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 23:47:33.809246 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 30 23:47:33.809253 kernel: Detected PIPT I-cache on CPU2 Oct 30 23:47:33.809260 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 30 23:47:33.809268 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 30 23:47:33.809275 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 23:47:33.809283 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 30 23:47:33.809290 kernel: Detected PIPT I-cache on CPU3 Oct 30 23:47:33.809298 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 30 23:47:33.809305 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 30 23:47:33.809312 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 23:47:33.809319 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 30 23:47:33.809326 kernel: smp: Brought up 1 node, 4 CPUs Oct 30 23:47:33.809333 kernel: SMP: Total of 4 processors activated. Oct 30 23:47:33.809340 kernel: CPU: All CPU(s) started at EL1 Oct 30 23:47:33.809350 kernel: CPU features: detected: 32-bit EL0 Support Oct 30 23:47:33.809357 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 30 23:47:33.809364 kernel: CPU features: detected: Common not Private translations Oct 30 23:47:33.809371 kernel: CPU features: detected: CRC32 instructions Oct 30 23:47:33.809378 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 30 23:47:33.809385 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 30 23:47:33.809392 kernel: CPU features: detected: LSE atomic instructions Oct 30 23:47:33.809398 kernel: CPU features: detected: Privileged Access Never Oct 30 23:47:33.809405 kernel: CPU features: detected: RAS Extension Support Oct 30 23:47:33.809414 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 30 23:47:33.809421 kernel: alternatives: applying system-wide alternatives Oct 30 23:47:33.809428 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 30 23:47:33.809435 kernel: Memory: 2424416K/2572288K available (11136K kernel code, 2450K rwdata, 9076K rodata, 38976K init, 1038K bss, 125536K reserved, 16384K cma-reserved) Oct 30 23:47:33.809442 kernel: devtmpfs: initialized Oct 30 23:47:33.809449 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 30 23:47:33.809456 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 30 23:47:33.809463 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 30 23:47:33.809470 kernel: 0 pages in range for non-PLT usage Oct 30 23:47:33.809479 kernel: 508560 pages in range for PLT usage Oct 30 23:47:33.809486 kernel: pinctrl core: initialized pinctrl subsystem Oct 30 23:47:33.809492 kernel: SMBIOS 3.0.0 present. Oct 30 23:47:33.809499 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 30 23:47:33.809507 kernel: DMI: Memory slots populated: 1/1 Oct 30 23:47:33.809514 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 30 23:47:33.809521 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 30 23:47:33.809528 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 30 23:47:33.809535 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 30 23:47:33.809543 kernel: audit: initializing netlink subsys (disabled) Oct 30 23:47:33.809550 kernel: audit: type=2000 audit(0.019:1): state=initialized audit_enabled=0 res=1 Oct 30 23:47:33.809557 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 30 23:47:33.809564 kernel: cpuidle: using governor menu Oct 30 23:47:33.809571 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 30 23:47:33.809578 kernel: ASID allocator initialised with 32768 entries Oct 30 23:47:33.809586 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 30 23:47:33.809592 kernel: Serial: AMBA PL011 UART driver Oct 30 23:47:33.809608 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 30 23:47:33.809617 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 30 23:47:33.809624 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 30 23:47:33.809632 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 30 23:47:33.809638 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 30 23:47:33.809646 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 30 23:47:33.809653 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 30 23:47:33.809660 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 30 23:47:33.809667 kernel: ACPI: Added _OSI(Module Device) Oct 30 23:47:33.809674 kernel: ACPI: Added _OSI(Processor Device) Oct 30 23:47:33.809681 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 30 23:47:33.809689 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 30 23:47:33.809709 kernel: ACPI: Interpreter enabled Oct 30 23:47:33.809719 kernel: ACPI: Using GIC for interrupt routing Oct 30 23:47:33.809726 kernel: ACPI: MCFG table detected, 1 entries Oct 30 23:47:33.809733 kernel: ACPI: CPU0 has been hot-added Oct 30 23:47:33.809740 kernel: ACPI: CPU1 has been hot-added Oct 30 23:47:33.809747 kernel: ACPI: CPU2 has been hot-added Oct 30 23:47:33.809754 kernel: ACPI: CPU3 has been hot-added Oct 30 23:47:33.809760 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 30 23:47:33.809770 kernel: printk: legacy console [ttyAMA0] enabled Oct 30 23:47:33.809777 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 30 23:47:33.809941 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 30 23:47:33.810011 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 30 23:47:33.810068 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 30 23:47:33.810124 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 30 23:47:33.810999 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 30 23:47:33.811022 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 30 23:47:33.811030 kernel: PCI host bridge to bus 0000:00 Oct 30 23:47:33.811128 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 30 23:47:33.811189 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 30 23:47:33.811246 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 30 23:47:33.811300 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 30 23:47:33.811392 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 30 23:47:33.811470 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 30 23:47:33.811536 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 30 23:47:33.811608 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 30 23:47:33.811676 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 30 23:47:33.811761 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 30 23:47:33.811826 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 30 23:47:33.811891 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 30 23:47:33.811951 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 30 23:47:33.812008 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 30 23:47:33.812067 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 30 23:47:33.812077 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 30 23:47:33.812084 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 30 23:47:33.812092 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 30 23:47:33.812100 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 30 23:47:33.812109 kernel: iommu: Default domain type: Translated Oct 30 23:47:33.812117 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 30 23:47:33.812124 kernel: efivars: Registered efivars operations Oct 30 23:47:33.812132 kernel: vgaarb: loaded Oct 30 23:47:33.812139 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 30 23:47:33.812146 kernel: VFS: Disk quotas dquot_6.6.0 Oct 30 23:47:33.812153 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 30 23:47:33.812160 kernel: pnp: PnP ACPI init Oct 30 23:47:33.812230 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 30 23:47:33.812242 kernel: pnp: PnP ACPI: found 1 devices Oct 30 23:47:33.812250 kernel: NET: Registered PF_INET protocol family Oct 30 23:47:33.812257 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 30 23:47:33.812265 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 30 23:47:33.812272 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 30 23:47:33.812280 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 30 23:47:33.812287 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 30 23:47:33.812294 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 30 23:47:33.812302 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 30 23:47:33.812311 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 30 23:47:33.812319 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 30 23:47:33.812326 kernel: PCI: CLS 0 bytes, default 64 Oct 30 23:47:33.812333 kernel: kvm [1]: HYP mode not available Oct 30 23:47:33.812341 kernel: Initialise system trusted keyrings Oct 30 23:47:33.812348 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 30 23:47:33.812355 kernel: Key type asymmetric registered Oct 30 23:47:33.812363 kernel: Asymmetric key parser 'x509' registered Oct 30 23:47:33.812370 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 30 23:47:33.812379 kernel: io scheduler mq-deadline registered Oct 30 23:47:33.812386 kernel: io scheduler kyber registered Oct 30 23:47:33.812393 kernel: io scheduler bfq registered Oct 30 23:47:33.812401 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 30 23:47:33.812408 kernel: ACPI: button: Power Button [PWRB] Oct 30 23:47:33.812415 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 30 23:47:33.812476 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 30 23:47:33.812486 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 30 23:47:33.812493 kernel: thunder_xcv, ver 1.0 Oct 30 23:47:33.812502 kernel: thunder_bgx, ver 1.0 Oct 30 23:47:33.812509 kernel: nicpf, ver 1.0 Oct 30 23:47:33.812517 kernel: nicvf, ver 1.0 Oct 30 23:47:33.812588 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 30 23:47:33.812662 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-30T23:47:33 UTC (1761868053) Oct 30 23:47:33.812673 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 30 23:47:33.812681 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 30 23:47:33.812688 kernel: watchdog: NMI not fully supported Oct 30 23:47:33.812712 kernel: watchdog: Hard watchdog permanently disabled Oct 30 23:47:33.812719 kernel: NET: Registered PF_INET6 protocol family Oct 30 23:47:33.812727 kernel: Segment Routing with IPv6 Oct 30 23:47:33.812734 kernel: In-situ OAM (IOAM) with IPv6 Oct 30 23:47:33.812741 kernel: NET: Registered PF_PACKET protocol family Oct 30 23:47:33.812748 kernel: Key type dns_resolver registered Oct 30 23:47:33.812755 kernel: registered taskstats version 1 Oct 30 23:47:33.812763 kernel: Loading compiled-in X.509 certificates Oct 30 23:47:33.812770 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 38254a600def0519df2d3cbc203d84bd87848ec9' Oct 30 23:47:33.812780 kernel: Demotion targets for Node 0: null Oct 30 23:47:33.812788 kernel: Key type .fscrypt registered Oct 30 23:47:33.812795 kernel: Key type fscrypt-provisioning registered Oct 30 23:47:33.812802 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 30 23:47:33.812809 kernel: ima: Allocated hash algorithm: sha1 Oct 30 23:47:33.812817 kernel: ima: No architecture policies found Oct 30 23:47:33.812824 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 30 23:47:33.812832 kernel: clk: Disabling unused clocks Oct 30 23:47:33.812839 kernel: PM: genpd: Disabling unused power domains Oct 30 23:47:33.812847 kernel: Warning: unable to open an initial console. Oct 30 23:47:33.812855 kernel: Freeing unused kernel memory: 38976K Oct 30 23:47:33.812877 kernel: Run /init as init process Oct 30 23:47:33.812884 kernel: with arguments: Oct 30 23:47:33.812892 kernel: /init Oct 30 23:47:33.812900 kernel: with environment: Oct 30 23:47:33.812907 kernel: HOME=/ Oct 30 23:47:33.812914 kernel: TERM=linux Oct 30 23:47:33.812922 systemd[1]: Successfully made /usr/ read-only. Oct 30 23:47:33.812934 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 30 23:47:33.812942 systemd[1]: Detected virtualization kvm. Oct 30 23:47:33.812949 systemd[1]: Detected architecture arm64. Oct 30 23:47:33.812957 systemd[1]: Running in initrd. Oct 30 23:47:33.812964 systemd[1]: No hostname configured, using default hostname. Oct 30 23:47:33.812972 systemd[1]: Hostname set to . Oct 30 23:47:33.812979 systemd[1]: Initializing machine ID from VM UUID. Oct 30 23:47:33.812988 systemd[1]: Queued start job for default target initrd.target. Oct 30 23:47:33.812996 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 23:47:33.813004 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 23:47:33.813012 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 30 23:47:33.813020 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 30 23:47:33.813028 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 30 23:47:33.813036 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 30 23:47:33.813047 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 30 23:47:33.813055 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 30 23:47:33.813063 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 23:47:33.813071 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 30 23:47:33.813079 systemd[1]: Reached target paths.target - Path Units. Oct 30 23:47:33.813086 systemd[1]: Reached target slices.target - Slice Units. Oct 30 23:47:33.813093 systemd[1]: Reached target swap.target - Swaps. Oct 30 23:47:33.813101 systemd[1]: Reached target timers.target - Timer Units. Oct 30 23:47:33.813110 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 30 23:47:33.813117 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 30 23:47:33.813125 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 30 23:47:33.813133 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 30 23:47:33.813141 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 30 23:47:33.813148 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 30 23:47:33.813156 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 23:47:33.813163 systemd[1]: Reached target sockets.target - Socket Units. Oct 30 23:47:33.813171 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 30 23:47:33.813189 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 30 23:47:33.813197 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 30 23:47:33.813206 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 30 23:47:33.813213 systemd[1]: Starting systemd-fsck-usr.service... Oct 30 23:47:33.813221 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 30 23:47:33.813229 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 30 23:47:33.813236 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 23:47:33.813244 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 30 23:47:33.813254 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 23:47:33.813262 systemd[1]: Finished systemd-fsck-usr.service. Oct 30 23:47:33.813270 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 30 23:47:33.813297 systemd-journald[246]: Collecting audit messages is disabled. Oct 30 23:47:33.813318 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 30 23:47:33.813326 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 23:47:33.813334 kernel: Bridge firewalling registered Oct 30 23:47:33.813342 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 30 23:47:33.813352 systemd-journald[246]: Journal started Oct 30 23:47:33.813370 systemd-journald[246]: Runtime Journal (/run/log/journal/ce8a64f80d564e708cdec7af2fdcb479) is 6M, max 48.5M, 42.4M free. Oct 30 23:47:33.796719 systemd-modules-load[247]: Inserted module 'overlay' Oct 30 23:47:33.811666 systemd-modules-load[247]: Inserted module 'br_netfilter' Oct 30 23:47:33.817423 systemd[1]: Started systemd-journald.service - Journal Service. Oct 30 23:47:33.830844 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 30 23:47:33.832177 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 30 23:47:33.836769 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 30 23:47:33.838434 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 30 23:47:33.840213 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 30 23:47:33.854864 systemd-tmpfiles[270]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 30 23:47:33.856519 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 23:47:33.857995 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 23:47:33.860332 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 30 23:47:33.862661 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 30 23:47:33.865168 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 30 23:47:33.868060 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 30 23:47:33.897774 dracut-cmdline[290]: 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=fe9a0b97d6cc3ae4bb51413f0dcf8829730d06a1c56255949a7891220815365c Oct 30 23:47:33.911806 systemd-resolved[291]: Positive Trust Anchors: Oct 30 23:47:33.911824 systemd-resolved[291]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 23:47:33.911856 systemd-resolved[291]: 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 30 23:47:33.921213 systemd-resolved[291]: Defaulting to hostname 'linux'. Oct 30 23:47:33.922467 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 30 23:47:33.923765 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 30 23:47:33.979733 kernel: SCSI subsystem initialized Oct 30 23:47:33.985725 kernel: Loading iSCSI transport class v2.0-870. Oct 30 23:47:33.994742 kernel: iscsi: registered transport (tcp) Oct 30 23:47:34.007725 kernel: iscsi: registered transport (qla4xxx) Oct 30 23:47:34.007784 kernel: QLogic iSCSI HBA Driver Oct 30 23:47:34.025382 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 30 23:47:34.048876 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 23:47:34.050685 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 30 23:47:34.103442 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 30 23:47:34.105978 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 30 23:47:34.171760 kernel: raid6: neonx8 gen() 15766 MB/s Oct 30 23:47:34.188747 kernel: raid6: neonx4 gen() 15795 MB/s Oct 30 23:47:34.205743 kernel: raid6: neonx2 gen() 13226 MB/s Oct 30 23:47:34.222747 kernel: raid6: neonx1 gen() 10447 MB/s Oct 30 23:47:34.239777 kernel: raid6: int64x8 gen() 6896 MB/s Oct 30 23:47:34.256753 kernel: raid6: int64x4 gen() 7341 MB/s Oct 30 23:47:34.273762 kernel: raid6: int64x2 gen() 6098 MB/s Oct 30 23:47:34.291009 kernel: raid6: int64x1 gen() 5036 MB/s Oct 30 23:47:34.291069 kernel: raid6: using algorithm neonx4 gen() 15795 MB/s Oct 30 23:47:34.308995 kernel: raid6: .... xor() 12295 MB/s, rmw enabled Oct 30 23:47:34.309050 kernel: raid6: using neon recovery algorithm Oct 30 23:47:34.314831 kernel: xor: measuring software checksum speed Oct 30 23:47:34.314868 kernel: 8regs : 21590 MB/sec Oct 30 23:47:34.316203 kernel: 32regs : 21681 MB/sec Oct 30 23:47:34.316220 kernel: arm64_neon : 26416 MB/sec Oct 30 23:47:34.316237 kernel: xor: using function: arm64_neon (26416 MB/sec) Oct 30 23:47:34.368736 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 30 23:47:34.375840 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 30 23:47:34.378382 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 23:47:34.407797 systemd-udevd[504]: Using default interface naming scheme 'v255'. Oct 30 23:47:34.412249 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 23:47:34.415424 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 30 23:47:34.440902 dracut-pre-trigger[511]: rd.md=0: removing MD RAID activation Oct 30 23:47:34.470329 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 30 23:47:34.473227 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 30 23:47:34.532773 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 23:47:34.535729 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 30 23:47:34.589075 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 30 23:47:34.589232 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Oct 30 23:47:34.597824 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 30 23:47:34.597879 kernel: GPT:9289727 != 19775487 Oct 30 23:47:34.599006 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 30 23:47:34.600183 kernel: GPT:9289727 != 19775487 Oct 30 23:47:34.600214 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 30 23:47:34.600224 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 23:47:34.602651 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 23:47:34.602800 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 23:47:34.607553 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 23:47:34.609847 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 23:47:34.633055 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 30 23:47:34.646311 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 30 23:47:34.649292 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 30 23:47:34.650799 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 23:47:34.668468 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 30 23:47:34.669875 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 30 23:47:34.678463 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 30 23:47:34.679783 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 30 23:47:34.681879 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 23:47:34.683948 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 30 23:47:34.686760 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 30 23:47:34.688684 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 30 23:47:34.709512 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 30 23:47:34.714716 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 23:47:34.714867 disk-uuid[595]: Primary Header is updated. Oct 30 23:47:34.714867 disk-uuid[595]: Secondary Entries is updated. Oct 30 23:47:34.714867 disk-uuid[595]: Secondary Header is updated. Oct 30 23:47:35.730726 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 23:47:35.730783 disk-uuid[604]: The operation has completed successfully. Oct 30 23:47:35.764835 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 30 23:47:35.764938 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 30 23:47:35.785773 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 30 23:47:35.815863 sh[615]: Success Oct 30 23:47:35.829179 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 30 23:47:35.829243 kernel: device-mapper: uevent: version 1.0.3 Oct 30 23:47:35.830622 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 30 23:47:35.839727 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 30 23:47:35.868582 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 30 23:47:35.870608 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 30 23:47:35.886933 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 30 23:47:35.897305 kernel: BTRFS: device fsid f92dbd85-4118-4758-bb41-80b2b70966d3 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (627) Oct 30 23:47:35.897349 kernel: BTRFS info (device dm-0): first mount of filesystem f92dbd85-4118-4758-bb41-80b2b70966d3 Oct 30 23:47:35.897360 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 30 23:47:35.905729 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 30 23:47:35.905777 kernel: BTRFS info (device dm-0): enabling free space tree Oct 30 23:47:35.907885 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 30 23:47:35.908980 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 30 23:47:35.910792 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 30 23:47:35.911555 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 30 23:47:35.913394 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 30 23:47:35.937892 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (656) Oct 30 23:47:35.937958 kernel: BTRFS info (device vda6): first mount of filesystem 186d34a8-58e2-4a7d-a93b-654a23856822 Oct 30 23:47:35.937968 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 30 23:47:35.942386 kernel: BTRFS info (device vda6): turning on async discard Oct 30 23:47:35.942432 kernel: BTRFS info (device vda6): enabling free space tree Oct 30 23:47:35.947718 kernel: BTRFS info (device vda6): last unmount of filesystem 186d34a8-58e2-4a7d-a93b-654a23856822 Oct 30 23:47:35.949753 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 30 23:47:35.955994 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 30 23:47:36.016012 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 30 23:47:36.019336 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 30 23:47:36.052437 systemd-networkd[799]: lo: Link UP Oct 30 23:47:36.052449 systemd-networkd[799]: lo: Gained carrier Oct 30 23:47:36.053235 systemd-networkd[799]: Enumeration completed Oct 30 23:47:36.053664 systemd-networkd[799]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 23:47:36.053667 systemd-networkd[799]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 23:47:36.054642 systemd-networkd[799]: eth0: Link UP Oct 30 23:47:36.054755 systemd-networkd[799]: eth0: Gained carrier Oct 30 23:47:36.054764 systemd-networkd[799]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 23:47:36.055161 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 30 23:47:36.061062 systemd[1]: Reached target network.target - Network. Oct 30 23:47:36.071593 ignition[716]: Ignition 2.22.0 Oct 30 23:47:36.071606 ignition[716]: Stage: fetch-offline Oct 30 23:47:36.071659 ignition[716]: no configs at "/usr/lib/ignition/base.d" Oct 30 23:47:36.071669 ignition[716]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 30 23:47:36.073778 systemd-networkd[799]: eth0: DHCPv4 address 10.0.0.44/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 30 23:47:36.071766 ignition[716]: parsed url from cmdline: "" Oct 30 23:47:36.071769 ignition[716]: no config URL provided Oct 30 23:47:36.071774 ignition[716]: reading system config file "/usr/lib/ignition/user.ign" Oct 30 23:47:36.071780 ignition[716]: no config at "/usr/lib/ignition/user.ign" Oct 30 23:47:36.071800 ignition[716]: op(1): [started] loading QEMU firmware config module Oct 30 23:47:36.071804 ignition[716]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 30 23:47:36.078655 ignition[716]: op(1): [finished] loading QEMU firmware config module Oct 30 23:47:36.078693 ignition[716]: QEMU firmware config was not found. Ignoring... Oct 30 23:47:36.088911 ignition[716]: parsing config with SHA512: 1d7cc38cb0b875fcd51271fc335754708bcad93d8d144f1e7b956ecc489634961c780d433fd7d7345ef244e192f5ddaedd2d13a1d9c1ae207da640be4b751afa Oct 30 23:47:36.093530 unknown[716]: fetched base config from "system" Oct 30 23:47:36.093545 unknown[716]: fetched user config from "qemu" Oct 30 23:47:36.094033 ignition[716]: fetch-offline: fetch-offline passed Oct 30 23:47:36.096596 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 30 23:47:36.094104 ignition[716]: Ignition finished successfully Oct 30 23:47:36.097952 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 30 23:47:36.098692 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 30 23:47:36.134266 ignition[813]: Ignition 2.22.0 Oct 30 23:47:36.134285 ignition[813]: Stage: kargs Oct 30 23:47:36.134435 ignition[813]: no configs at "/usr/lib/ignition/base.d" Oct 30 23:47:36.134445 ignition[813]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 30 23:47:36.137285 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 30 23:47:36.135105 ignition[813]: kargs: kargs passed Oct 30 23:47:36.135148 ignition[813]: Ignition finished successfully Oct 30 23:47:36.139761 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 30 23:47:36.172718 ignition[823]: Ignition 2.22.0 Oct 30 23:47:36.172736 ignition[823]: Stage: disks Oct 30 23:47:36.172882 ignition[823]: no configs at "/usr/lib/ignition/base.d" Oct 30 23:47:36.172893 ignition[823]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 30 23:47:36.175253 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 30 23:47:36.173491 ignition[823]: disks: disks passed Oct 30 23:47:36.177376 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 30 23:47:36.173534 ignition[823]: Ignition finished successfully Oct 30 23:47:36.179123 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 30 23:47:36.180976 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 30 23:47:36.182957 systemd[1]: Reached target sysinit.target - System Initialization. Oct 30 23:47:36.184640 systemd[1]: Reached target basic.target - Basic System. Oct 30 23:47:36.187589 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 30 23:47:36.211129 systemd-fsck[833]: ROOT: clean, 15/553520 files, 52789/553472 blocks Oct 30 23:47:36.216186 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 30 23:47:36.218461 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 30 23:47:36.287731 kernel: EXT4-fs (vda9): mounted filesystem 8b3ccddf-a30d-4ebb-ae71-ca86224f5ff5 r/w with ordered data mode. Quota mode: none. Oct 30 23:47:36.288494 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 30 23:47:36.289758 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 30 23:47:36.292018 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 30 23:47:36.293577 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 30 23:47:36.294560 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 30 23:47:36.294612 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 30 23:47:36.294636 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 30 23:47:36.307315 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 30 23:47:36.309383 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 30 23:47:36.315751 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (841) Oct 30 23:47:36.318209 kernel: BTRFS info (device vda6): first mount of filesystem 186d34a8-58e2-4a7d-a93b-654a23856822 Oct 30 23:47:36.318254 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 30 23:47:36.321725 kernel: BTRFS info (device vda6): turning on async discard Oct 30 23:47:36.321773 kernel: BTRFS info (device vda6): enabling free space tree Oct 30 23:47:36.323316 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 30 23:47:36.348779 initrd-setup-root[865]: cut: /sysroot/etc/passwd: No such file or directory Oct 30 23:47:36.352319 initrd-setup-root[872]: cut: /sysroot/etc/group: No such file or directory Oct 30 23:47:36.355609 initrd-setup-root[879]: cut: /sysroot/etc/shadow: No such file or directory Oct 30 23:47:36.358636 initrd-setup-root[886]: cut: /sysroot/etc/gshadow: No such file or directory Oct 30 23:47:36.428648 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 30 23:47:36.430757 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 30 23:47:36.432366 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 30 23:47:36.457734 kernel: BTRFS info (device vda6): last unmount of filesystem 186d34a8-58e2-4a7d-a93b-654a23856822 Oct 30 23:47:36.470871 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 30 23:47:36.484329 ignition[955]: INFO : Ignition 2.22.0 Oct 30 23:47:36.484329 ignition[955]: INFO : Stage: mount Oct 30 23:47:36.486058 ignition[955]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 23:47:36.486058 ignition[955]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 30 23:47:36.486058 ignition[955]: INFO : mount: mount passed Oct 30 23:47:36.486058 ignition[955]: INFO : Ignition finished successfully Oct 30 23:47:36.487278 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 30 23:47:36.490165 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 30 23:47:36.895497 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 30 23:47:36.897134 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 30 23:47:36.929733 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (967) Oct 30 23:47:36.932070 kernel: BTRFS info (device vda6): first mount of filesystem 186d34a8-58e2-4a7d-a93b-654a23856822 Oct 30 23:47:36.932094 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 30 23:47:36.934727 kernel: BTRFS info (device vda6): turning on async discard Oct 30 23:47:36.934753 kernel: BTRFS info (device vda6): enabling free space tree Oct 30 23:47:36.936012 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 30 23:47:36.962186 ignition[984]: INFO : Ignition 2.22.0 Oct 30 23:47:36.962186 ignition[984]: INFO : Stage: files Oct 30 23:47:36.963941 ignition[984]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 23:47:36.963941 ignition[984]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 30 23:47:36.963941 ignition[984]: DEBUG : files: compiled without relabeling support, skipping Oct 30 23:47:36.967386 ignition[984]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 30 23:47:36.967386 ignition[984]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 30 23:47:36.970047 ignition[984]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 30 23:47:36.971356 ignition[984]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 30 23:47:36.971356 ignition[984]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 30 23:47:36.970533 unknown[984]: wrote ssh authorized keys file for user: core Oct 30 23:47:36.975066 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 30 23:47:36.975066 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 30 23:47:36.979260 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 30 23:47:36.981092 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 30 23:47:36.981092 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 30 23:47:36.985263 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 30 23:47:36.987874 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 30 23:47:36.987874 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Oct 30 23:47:37.160846 systemd-networkd[799]: eth0: Gained IPv6LL Oct 30 23:47:37.397473 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 30 23:47:37.691129 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 30 23:47:37.691129 ignition[984]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 30 23:47:37.694667 ignition[984]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 30 23:47:37.698823 ignition[984]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 30 23:47:37.698823 ignition[984]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 30 23:47:37.698823 ignition[984]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 30 23:47:37.713994 ignition[984]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 30 23:47:37.717294 ignition[984]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 30 23:47:37.718899 ignition[984]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 30 23:47:37.718899 ignition[984]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 30 23:47:37.718899 ignition[984]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 30 23:47:37.718899 ignition[984]: INFO : files: files passed Oct 30 23:47:37.718899 ignition[984]: INFO : Ignition finished successfully Oct 30 23:47:37.720565 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 30 23:47:37.722903 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 30 23:47:37.725727 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 30 23:47:37.742722 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 30 23:47:37.742826 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 30 23:47:37.746154 initrd-setup-root-after-ignition[1013]: grep: /sysroot/oem/oem-release: No such file or directory Oct 30 23:47:37.748782 initrd-setup-root-after-ignition[1015]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 30 23:47:37.748782 initrd-setup-root-after-ignition[1015]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 30 23:47:37.751870 initrd-setup-root-after-ignition[1019]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 30 23:47:37.754517 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 30 23:47:37.756093 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 30 23:47:37.758853 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 30 23:47:37.795276 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 30 23:47:37.795421 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 30 23:47:37.797566 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 30 23:47:37.799393 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 30 23:47:37.801265 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 30 23:47:37.802083 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 30 23:47:37.820548 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 30 23:47:37.823303 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 30 23:47:37.848453 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 30 23:47:37.849773 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 23:47:37.851903 systemd[1]: Stopped target timers.target - Timer Units. Oct 30 23:47:37.853662 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 30 23:47:37.853802 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 30 23:47:37.856416 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 30 23:47:37.858361 systemd[1]: Stopped target basic.target - Basic System. Oct 30 23:47:37.859933 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 30 23:47:37.861598 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 30 23:47:37.863520 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 30 23:47:37.865479 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 30 23:47:37.867334 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 30 23:47:37.869120 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 30 23:47:37.871013 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 30 23:47:37.872871 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 30 23:47:37.874624 systemd[1]: Stopped target swap.target - Swaps. Oct 30 23:47:37.876149 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 30 23:47:37.876275 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 30 23:47:37.878558 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 30 23:47:37.879716 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 23:47:37.881637 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 30 23:47:37.885778 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 23:47:37.886959 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 30 23:47:37.887076 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 30 23:47:37.889802 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 30 23:47:37.889918 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 30 23:47:37.891875 systemd[1]: Stopped target paths.target - Path Units. Oct 30 23:47:37.893432 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 30 23:47:37.896810 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 23:47:37.898055 systemd[1]: Stopped target slices.target - Slice Units. Oct 30 23:47:37.900136 systemd[1]: Stopped target sockets.target - Socket Units. Oct 30 23:47:37.901632 systemd[1]: iscsid.socket: Deactivated successfully. Oct 30 23:47:37.901744 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 30 23:47:37.903275 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 30 23:47:37.903360 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 30 23:47:37.904844 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 30 23:47:37.904963 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 30 23:47:37.906766 systemd[1]: ignition-files.service: Deactivated successfully. Oct 30 23:47:37.906874 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 30 23:47:37.909461 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 30 23:47:37.912058 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 30 23:47:37.913178 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 30 23:47:37.913298 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 23:47:37.915087 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 30 23:47:37.915195 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 30 23:47:37.921989 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 30 23:47:37.922097 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 30 23:47:37.927285 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 30 23:47:37.934231 ignition[1039]: INFO : Ignition 2.22.0 Oct 30 23:47:37.934231 ignition[1039]: INFO : Stage: umount Oct 30 23:47:37.935841 ignition[1039]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 23:47:37.935841 ignition[1039]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 30 23:47:37.935841 ignition[1039]: INFO : umount: umount passed Oct 30 23:47:37.935841 ignition[1039]: INFO : Ignition finished successfully Oct 30 23:47:37.936913 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 30 23:47:37.937050 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 30 23:47:37.938886 systemd[1]: Stopped target network.target - Network. Oct 30 23:47:37.940218 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 30 23:47:37.940277 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 30 23:47:37.941842 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 30 23:47:37.941885 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 30 23:47:37.943595 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 30 23:47:37.943648 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 30 23:47:37.945472 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 30 23:47:37.945513 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 30 23:47:37.947301 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 30 23:47:37.948950 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 30 23:47:37.957020 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 30 23:47:37.957153 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 30 23:47:37.961660 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 30 23:47:37.961939 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 30 23:47:37.962028 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 30 23:47:37.965321 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 30 23:47:37.965865 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 30 23:47:37.967918 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 30 23:47:37.967953 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 30 23:47:37.970543 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 30 23:47:37.971499 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 30 23:47:37.971560 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 30 23:47:37.973856 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 30 23:47:37.973904 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 30 23:47:37.976719 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 30 23:47:37.976771 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 30 23:47:37.979008 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 30 23:47:37.979055 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 23:47:37.982084 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 23:47:37.985411 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 30 23:47:37.985467 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 30 23:47:37.996271 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 30 23:47:37.996395 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 30 23:47:37.998426 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 30 23:47:37.998543 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 23:47:38.000276 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 30 23:47:38.000338 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 30 23:47:38.001754 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 30 23:47:38.001787 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 23:47:38.003621 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 30 23:47:38.003665 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 30 23:47:38.006665 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 30 23:47:38.006727 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 30 23:47:38.009621 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 30 23:47:38.009672 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 30 23:47:38.013277 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 30 23:47:38.014592 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 30 23:47:38.014651 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 23:47:38.017490 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 30 23:47:38.017534 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 23:47:38.021007 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 30 23:47:38.021056 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 30 23:47:38.024620 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 30 23:47:38.024669 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 23:47:38.027260 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 23:47:38.027305 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 23:47:38.031502 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Oct 30 23:47:38.031559 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Oct 30 23:47:38.031597 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 30 23:47:38.031630 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 30 23:47:38.031971 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 30 23:47:38.032054 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 30 23:47:38.117427 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 30 23:47:38.117546 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 30 23:47:38.120025 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 30 23:47:38.121244 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 30 23:47:38.121313 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 30 23:47:38.124637 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 30 23:47:38.140129 systemd[1]: Switching root. Oct 30 23:47:38.172930 systemd-journald[246]: Journal stopped Oct 30 23:47:39.026636 systemd-journald[246]: Received SIGTERM from PID 1 (systemd). Oct 30 23:47:39.026694 kernel: SELinux: policy capability network_peer_controls=1 Oct 30 23:47:39.026733 kernel: SELinux: policy capability open_perms=1 Oct 30 23:47:39.026744 kernel: SELinux: policy capability extended_socket_class=1 Oct 30 23:47:39.026755 kernel: SELinux: policy capability always_check_network=0 Oct 30 23:47:39.026765 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 30 23:47:39.026774 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 30 23:47:39.026783 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 30 23:47:39.026792 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 30 23:47:39.026805 kernel: SELinux: policy capability userspace_initial_context=0 Oct 30 23:47:39.026815 kernel: audit: type=1403 audit(1761868058.401:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 30 23:47:39.026829 systemd[1]: Successfully loaded SELinux policy in 45.431ms. Oct 30 23:47:39.026849 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.592ms. Oct 30 23:47:39.026860 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 30 23:47:39.026872 systemd[1]: Detected virtualization kvm. Oct 30 23:47:39.026882 systemd[1]: Detected architecture arm64. Oct 30 23:47:39.026891 systemd[1]: Detected first boot. Oct 30 23:47:39.026901 systemd[1]: Initializing machine ID from VM UUID. Oct 30 23:47:39.026911 kernel: NET: Registered PF_VSOCK protocol family Oct 30 23:47:39.026920 zram_generator::config[1085]: No configuration found. Oct 30 23:47:39.026930 systemd[1]: Populated /etc with preset unit settings. Oct 30 23:47:39.026943 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 30 23:47:39.026956 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 30 23:47:39.026965 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 30 23:47:39.026975 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 30 23:47:39.026985 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 30 23:47:39.026995 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 30 23:47:39.027005 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 30 23:47:39.027015 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 30 23:47:39.027027 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 30 23:47:39.027037 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 30 23:47:39.027047 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 30 23:47:39.027056 systemd[1]: Created slice user.slice - User and Session Slice. Oct 30 23:47:39.027066 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 23:47:39.027076 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 23:47:39.027087 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 30 23:47:39.027097 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 30 23:47:39.027107 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 30 23:47:39.027118 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 30 23:47:39.027128 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 30 23:47:39.027138 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 23:47:39.027148 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 30 23:47:39.027157 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 30 23:47:39.027167 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 30 23:47:39.027177 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 30 23:47:39.027187 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 30 23:47:39.027199 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 23:47:39.027209 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 30 23:47:39.027219 systemd[1]: Reached target slices.target - Slice Units. Oct 30 23:47:39.027228 systemd[1]: Reached target swap.target - Swaps. Oct 30 23:47:39.027238 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 30 23:47:39.027248 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 30 23:47:39.027257 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 30 23:47:39.027267 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 30 23:47:39.027277 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 30 23:47:39.027289 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 23:47:39.027299 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 30 23:47:39.027308 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 30 23:47:39.027318 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 30 23:47:39.027328 systemd[1]: Mounting media.mount - External Media Directory... Oct 30 23:47:39.027338 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 30 23:47:39.027347 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 30 23:47:39.027358 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 30 23:47:39.027368 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 30 23:47:39.027380 systemd[1]: Reached target machines.target - Containers. Oct 30 23:47:39.027392 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 30 23:47:39.027402 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 23:47:39.027412 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 30 23:47:39.027422 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 30 23:47:39.027432 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 23:47:39.027442 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 30 23:47:39.027452 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 23:47:39.027464 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 30 23:47:39.027474 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 23:47:39.027485 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 30 23:47:39.027494 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 30 23:47:39.027504 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 30 23:47:39.027515 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 30 23:47:39.027525 systemd[1]: Stopped systemd-fsck-usr.service. Oct 30 23:47:39.027536 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 30 23:47:39.027547 kernel: loop: module loaded Oct 30 23:47:39.027556 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 30 23:47:39.027566 kernel: fuse: init (API version 7.41) Oct 30 23:47:39.027584 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 30 23:47:39.027595 kernel: ACPI: bus type drm_connector registered Oct 30 23:47:39.027605 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 30 23:47:39.027615 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 30 23:47:39.027625 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 30 23:47:39.027636 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 30 23:47:39.027647 systemd[1]: verity-setup.service: Deactivated successfully. Oct 30 23:47:39.027657 systemd[1]: Stopped verity-setup.service. Oct 30 23:47:39.027666 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 30 23:47:39.027708 systemd-journald[1159]: Collecting audit messages is disabled. Oct 30 23:47:39.027735 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 30 23:47:39.027747 systemd-journald[1159]: Journal started Oct 30 23:47:39.027769 systemd-journald[1159]: Runtime Journal (/run/log/journal/ce8a64f80d564e708cdec7af2fdcb479) is 6M, max 48.5M, 42.4M free. Oct 30 23:47:38.796797 systemd[1]: Queued start job for default target multi-user.target. Oct 30 23:47:38.819739 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 30 23:47:38.820127 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 30 23:47:39.030728 systemd[1]: Started systemd-journald.service - Journal Service. Oct 30 23:47:39.031312 systemd[1]: Mounted media.mount - External Media Directory. Oct 30 23:47:39.032485 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 30 23:47:39.033761 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 30 23:47:39.035006 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 30 23:47:39.036304 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 30 23:47:39.037819 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 23:47:39.039250 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 30 23:47:39.039420 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 30 23:47:39.041112 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 23:47:39.041286 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 23:47:39.042763 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 23:47:39.042922 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 30 23:47:39.044223 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 23:47:39.044402 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 23:47:39.046238 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 30 23:47:39.046397 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 30 23:47:39.047868 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 23:47:39.048034 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 23:47:39.049646 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 30 23:47:39.051201 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 23:47:39.053139 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 30 23:47:39.054865 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 30 23:47:39.067055 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 30 23:47:39.069438 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 30 23:47:39.071603 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 30 23:47:39.072909 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 30 23:47:39.072949 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 30 23:47:39.074828 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 30 23:47:39.085542 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 30 23:47:39.086897 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 23:47:39.087953 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 30 23:47:39.090022 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 30 23:47:39.091338 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 30 23:47:39.092329 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 30 23:47:39.095794 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 30 23:47:39.097028 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 30 23:47:39.097786 systemd-journald[1159]: Time spent on flushing to /var/log/journal/ce8a64f80d564e708cdec7af2fdcb479 is 15.774ms for 872 entries. Oct 30 23:47:39.097786 systemd-journald[1159]: System Journal (/var/log/journal/ce8a64f80d564e708cdec7af2fdcb479) is 8M, max 195.6M, 187.6M free. Oct 30 23:47:39.121893 systemd-journald[1159]: Received client request to flush runtime journal. Oct 30 23:47:39.100360 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 30 23:47:39.102968 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 30 23:47:39.111034 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 23:47:39.112846 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 30 23:47:39.116066 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 30 23:47:39.118589 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 30 23:47:39.122767 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 30 23:47:39.126129 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 30 23:47:39.126775 kernel: loop0: detected capacity change from 0 to 100632 Oct 30 23:47:39.128279 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 30 23:47:39.140195 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 30 23:47:39.147228 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 30 23:47:39.148562 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Oct 30 23:47:39.148970 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Oct 30 23:47:39.153855 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 30 23:47:39.157080 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 30 23:47:39.163413 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 30 23:47:39.184759 kernel: loop1: detected capacity change from 0 to 211168 Oct 30 23:47:39.202064 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 30 23:47:39.204641 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 30 23:47:39.211727 kernel: loop2: detected capacity change from 0 to 119368 Oct 30 23:47:39.223778 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Oct 30 23:47:39.223797 systemd-tmpfiles[1223]: ACLs are not supported, ignoring. Oct 30 23:47:39.227835 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 23:47:39.240731 kernel: loop3: detected capacity change from 0 to 100632 Oct 30 23:47:39.245741 kernel: loop4: detected capacity change from 0 to 211168 Oct 30 23:47:39.251738 kernel: loop5: detected capacity change from 0 to 119368 Oct 30 23:47:39.255966 (sd-merge)[1227]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Oct 30 23:47:39.256348 (sd-merge)[1227]: Merged extensions into '/usr'. Oct 30 23:47:39.259967 systemd[1]: Reload requested from client PID 1200 ('systemd-sysext') (unit systemd-sysext.service)... Oct 30 23:47:39.259988 systemd[1]: Reloading... Oct 30 23:47:39.324765 zram_generator::config[1249]: No configuration found. Oct 30 23:47:39.374151 ldconfig[1195]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 30 23:47:39.466766 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 30 23:47:39.467142 systemd[1]: Reloading finished in 206 ms. Oct 30 23:47:39.484334 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 30 23:47:39.485891 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 30 23:47:39.499013 systemd[1]: Starting ensure-sysext.service... Oct 30 23:47:39.500795 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 30 23:47:39.513988 systemd-tmpfiles[1288]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 30 23:47:39.514022 systemd-tmpfiles[1288]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 30 23:47:39.514243 systemd-tmpfiles[1288]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 30 23:47:39.514423 systemd-tmpfiles[1288]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 30 23:47:39.515048 systemd-tmpfiles[1288]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 30 23:47:39.515257 systemd-tmpfiles[1288]: ACLs are not supported, ignoring. Oct 30 23:47:39.515306 systemd-tmpfiles[1288]: ACLs are not supported, ignoring. Oct 30 23:47:39.516307 systemd[1]: Reload requested from client PID 1287 ('systemctl') (unit ensure-sysext.service)... Oct 30 23:47:39.516321 systemd[1]: Reloading... Oct 30 23:47:39.518342 systemd-tmpfiles[1288]: Detected autofs mount point /boot during canonicalization of boot. Oct 30 23:47:39.518358 systemd-tmpfiles[1288]: Skipping /boot Oct 30 23:47:39.524306 systemd-tmpfiles[1288]: Detected autofs mount point /boot during canonicalization of boot. Oct 30 23:47:39.524325 systemd-tmpfiles[1288]: Skipping /boot Oct 30 23:47:39.554729 zram_generator::config[1312]: No configuration found. Oct 30 23:47:39.692354 systemd[1]: Reloading finished in 175 ms. Oct 30 23:47:39.712422 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 30 23:47:39.718403 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 23:47:39.726724 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 30 23:47:39.729444 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 30 23:47:39.732771 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 30 23:47:39.737871 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 30 23:47:39.741028 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 23:47:39.743848 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 30 23:47:39.752763 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 23:47:39.767116 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 23:47:39.770945 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 23:47:39.774800 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 23:47:39.776743 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 23:47:39.776878 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 30 23:47:39.779303 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 30 23:47:39.782833 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 30 23:47:39.785210 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 23:47:39.785446 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 23:47:39.787649 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 23:47:39.787881 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 23:47:39.789835 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 23:47:39.790212 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 23:47:39.792293 augenrules[1380]: No rules Oct 30 23:47:39.798491 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 23:47:39.798756 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 30 23:47:39.800448 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 30 23:47:39.803006 systemd-udevd[1361]: Using default interface naming scheme 'v255'. Oct 30 23:47:39.806669 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 30 23:47:39.812641 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 30 23:47:39.814202 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 23:47:39.815983 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 23:47:39.824904 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 30 23:47:39.829004 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 23:47:39.831393 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 23:47:39.832943 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 23:47:39.833083 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 30 23:47:39.835928 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 30 23:47:39.837000 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 30 23:47:39.839354 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 30 23:47:39.843335 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 23:47:39.845371 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 23:47:39.845576 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 23:47:39.854771 systemd[1]: Finished ensure-sysext.service. Oct 30 23:47:39.860150 augenrules[1392]: /sbin/augenrules: No change Oct 30 23:47:39.864929 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 30 23:47:39.871061 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 30 23:47:39.873831 augenrules[1450]: No rules Oct 30 23:47:39.877439 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 23:47:39.877674 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 30 23:47:39.880661 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 23:47:39.881936 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 23:47:39.884105 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 23:47:39.884877 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 23:47:39.887022 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 30 23:47:39.887078 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 30 23:47:39.890038 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 23:47:39.890200 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 30 23:47:39.897062 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 30 23:47:39.924098 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 30 23:47:39.956738 systemd-resolved[1355]: Positive Trust Anchors: Oct 30 23:47:39.956760 systemd-resolved[1355]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 23:47:39.956792 systemd-resolved[1355]: 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 30 23:47:39.966281 systemd-resolved[1355]: Defaulting to hostname 'linux'. Oct 30 23:47:39.967977 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 30 23:47:39.969326 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 30 23:47:39.982867 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 30 23:47:39.984849 systemd[1]: Reached target sysinit.target - System Initialization. Oct 30 23:47:39.986017 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 30 23:47:39.987764 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 30 23:47:39.989239 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 30 23:47:39.991037 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 30 23:47:39.991067 systemd[1]: Reached target paths.target - Path Units. Oct 30 23:47:39.992398 systemd[1]: Reached target time-set.target - System Time Set. Oct 30 23:47:39.993669 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 30 23:47:39.994900 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 30 23:47:39.996113 systemd[1]: Reached target timers.target - Timer Units. Oct 30 23:47:39.998489 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 30 23:47:40.001613 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 30 23:47:40.002274 systemd-networkd[1446]: lo: Link UP Oct 30 23:47:40.002287 systemd-networkd[1446]: lo: Gained carrier Oct 30 23:47:40.003126 systemd-networkd[1446]: Enumeration completed Oct 30 23:47:40.004846 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 30 23:47:40.006504 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 30 23:47:40.007927 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 30 23:47:40.010962 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 30 23:47:40.011121 systemd-networkd[1446]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 23:47:40.011126 systemd-networkd[1446]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 23:47:40.011754 systemd-networkd[1446]: eth0: Link UP Oct 30 23:47:40.011873 systemd-networkd[1446]: eth0: Gained carrier Oct 30 23:47:40.011890 systemd-networkd[1446]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 23:47:40.013064 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 30 23:47:40.014914 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 30 23:47:40.016170 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 30 23:47:40.021068 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 30 23:47:40.022418 systemd[1]: Reached target network.target - Network. Oct 30 23:47:40.023386 systemd[1]: Reached target sockets.target - Socket Units. Oct 30 23:47:40.024404 systemd[1]: Reached target basic.target - Basic System. Oct 30 23:47:40.025878 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 30 23:47:40.025909 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 30 23:47:40.027799 systemd[1]: Starting containerd.service - containerd container runtime... Oct 30 23:47:40.032928 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 30 23:47:40.036282 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 30 23:47:40.039777 systemd-networkd[1446]: eth0: DHCPv4 address 10.0.0.44/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 30 23:47:40.040320 systemd-timesyncd[1449]: Network configuration changed, trying to establish connection. Oct 30 23:47:40.040879 systemd-timesyncd[1449]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 30 23:47:40.040936 systemd-timesyncd[1449]: Initial clock synchronization to Thu 2025-10-30 23:47:39.680751 UTC. Oct 30 23:47:40.047882 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 30 23:47:40.049905 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 30 23:47:40.051328 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 30 23:47:40.054089 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 30 23:47:40.056725 jq[1488]: false Oct 30 23:47:40.059600 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 30 23:47:40.062604 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 30 23:47:40.065839 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 30 23:47:40.072268 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 30 23:47:40.074660 extend-filesystems[1489]: Found /dev/vda6 Oct 30 23:47:40.075226 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 30 23:47:40.083002 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 30 23:47:40.085250 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 30 23:47:40.085792 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 30 23:47:40.086519 systemd[1]: Starting update-engine.service - Update Engine... Oct 30 23:47:40.088603 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 30 23:47:40.090065 extend-filesystems[1489]: Found /dev/vda9 Oct 30 23:47:40.091501 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 30 23:47:40.094141 extend-filesystems[1489]: Checking size of /dev/vda9 Oct 30 23:47:40.094425 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 30 23:47:40.104999 extend-filesystems[1489]: Resized partition /dev/vda9 Oct 30 23:47:40.107044 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 30 23:47:40.111230 extend-filesystems[1519]: resize2fs 1.47.3 (8-Jul-2025) Oct 30 23:47:40.107443 systemd[1]: motdgen.service: Deactivated successfully. Oct 30 23:47:40.107731 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 30 23:47:40.109174 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 30 23:47:40.109345 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 30 23:47:40.111539 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 30 23:47:40.117718 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Oct 30 23:47:40.122324 jq[1511]: true Oct 30 23:47:40.135306 update_engine[1509]: I20251030 23:47:40.134476 1509 main.cc:92] Flatcar Update Engine starting Oct 30 23:47:40.140258 (ntainerd)[1520]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 30 23:47:40.150022 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 23:47:40.153584 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 30 23:47:40.154721 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Oct 30 23:47:40.174104 jq[1525]: true Oct 30 23:47:40.175089 dbus-daemon[1486]: [system] SELinux support is enabled Oct 30 23:47:40.175270 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 30 23:47:40.176825 extend-filesystems[1519]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 30 23:47:40.176825 extend-filesystems[1519]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 30 23:47:40.176825 extend-filesystems[1519]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Oct 30 23:47:40.181480 extend-filesystems[1489]: Resized filesystem in /dev/vda9 Oct 30 23:47:40.179838 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 30 23:47:40.183048 update_engine[1509]: I20251030 23:47:40.182847 1509 update_check_scheduler.cc:74] Next update check in 2m35s Oct 30 23:47:40.187415 systemd-logind[1498]: Watching system buttons on /dev/input/event0 (Power Button) Oct 30 23:47:40.187669 systemd-logind[1498]: New seat seat0. Oct 30 23:47:40.189856 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 30 23:47:40.195052 systemd[1]: Started systemd-logind.service - User Login Management. Oct 30 23:47:40.196815 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 30 23:47:40.196869 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 30 23:47:40.197826 dbus-daemon[1486]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 30 23:47:40.198890 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 30 23:47:40.198963 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 30 23:47:40.202927 systemd[1]: Started update-engine.service - Update Engine. Oct 30 23:47:40.209022 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 30 23:47:40.224379 bash[1554]: Updated "/home/core/.ssh/authorized_keys" Oct 30 23:47:40.231339 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 30 23:47:40.233843 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 30 23:47:40.255154 locksmithd[1547]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 30 23:47:40.257485 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 23:47:40.316395 containerd[1520]: time="2025-10-30T23:47:40Z" 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 30 23:47:40.317360 containerd[1520]: time="2025-10-30T23:47:40.317307560Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 30 23:47:40.327359 containerd[1520]: time="2025-10-30T23:47:40.327313280Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.08µs" Oct 30 23:47:40.327359 containerd[1520]: time="2025-10-30T23:47:40.327350320Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 30 23:47:40.327447 containerd[1520]: time="2025-10-30T23:47:40.327369720Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 30 23:47:40.327557 containerd[1520]: time="2025-10-30T23:47:40.327522640Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 30 23:47:40.327557 containerd[1520]: time="2025-10-30T23:47:40.327547560Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 30 23:47:40.327604 containerd[1520]: time="2025-10-30T23:47:40.327582960Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 30 23:47:40.327655 containerd[1520]: time="2025-10-30T23:47:40.327637440Z" 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 30 23:47:40.327675 containerd[1520]: time="2025-10-30T23:47:40.327653360Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 30 23:47:40.327924 containerd[1520]: time="2025-10-30T23:47:40.327886000Z" 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 30 23:47:40.327924 containerd[1520]: time="2025-10-30T23:47:40.327911560Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 30 23:47:40.327924 containerd[1520]: time="2025-10-30T23:47:40.327923600Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 30 23:47:40.327990 containerd[1520]: time="2025-10-30T23:47:40.327932080Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 30 23:47:40.328029 containerd[1520]: time="2025-10-30T23:47:40.328012400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 30 23:47:40.328234 containerd[1520]: time="2025-10-30T23:47:40.328202560Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 30 23:47:40.328260 containerd[1520]: time="2025-10-30T23:47:40.328240600Z" 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 30 23:47:40.328260 containerd[1520]: time="2025-10-30T23:47:40.328252360Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 30 23:47:40.328299 containerd[1520]: time="2025-10-30T23:47:40.328283720Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 30 23:47:40.328908 containerd[1520]: time="2025-10-30T23:47:40.328876480Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 30 23:47:40.328985 containerd[1520]: time="2025-10-30T23:47:40.328967920Z" level=info msg="metadata content store policy set" policy=shared Oct 30 23:47:40.332849 containerd[1520]: time="2025-10-30T23:47:40.332800520Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 30 23:47:40.332898 containerd[1520]: time="2025-10-30T23:47:40.332881080Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 30 23:47:40.332934 containerd[1520]: time="2025-10-30T23:47:40.332897480Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 30 23:47:40.332934 containerd[1520]: time="2025-10-30T23:47:40.332911640Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 30 23:47:40.332934 containerd[1520]: time="2025-10-30T23:47:40.332924840Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 30 23:47:40.332990 containerd[1520]: time="2025-10-30T23:47:40.332936200Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 30 23:47:40.332990 containerd[1520]: time="2025-10-30T23:47:40.332948280Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 30 23:47:40.332990 containerd[1520]: time="2025-10-30T23:47:40.332959640Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 30 23:47:40.332990 containerd[1520]: time="2025-10-30T23:47:40.332971160Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 30 23:47:40.332990 containerd[1520]: time="2025-10-30T23:47:40.332981960Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 30 23:47:40.333071 containerd[1520]: time="2025-10-30T23:47:40.332991400Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 30 23:47:40.333071 containerd[1520]: time="2025-10-30T23:47:40.333003840Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 30 23:47:40.333148 containerd[1520]: time="2025-10-30T23:47:40.333126840Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 30 23:47:40.333172 containerd[1520]: time="2025-10-30T23:47:40.333154600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 30 23:47:40.333189 containerd[1520]: time="2025-10-30T23:47:40.333170120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 30 23:47:40.333189 containerd[1520]: time="2025-10-30T23:47:40.333181440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 30 23:47:40.333226 containerd[1520]: time="2025-10-30T23:47:40.333192480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 30 23:47:40.333226 containerd[1520]: time="2025-10-30T23:47:40.333204000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 30 23:47:40.333226 containerd[1520]: time="2025-10-30T23:47:40.333216200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 30 23:47:40.333279 containerd[1520]: time="2025-10-30T23:47:40.333229400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 30 23:47:40.333279 containerd[1520]: time="2025-10-30T23:47:40.333245080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 30 23:47:40.333279 containerd[1520]: time="2025-10-30T23:47:40.333255960Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 30 23:47:40.333279 containerd[1520]: time="2025-10-30T23:47:40.333266480Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 30 23:47:40.333477 containerd[1520]: time="2025-10-30T23:47:40.333446720Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 30 23:47:40.333477 containerd[1520]: time="2025-10-30T23:47:40.333469960Z" level=info msg="Start snapshots syncer" Oct 30 23:47:40.333517 containerd[1520]: time="2025-10-30T23:47:40.333497120Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 30 23:47:40.333817 containerd[1520]: time="2025-10-30T23:47:40.333777400Z" 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 30 23:47:40.333901 containerd[1520]: time="2025-10-30T23:47:40.333834880Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 30 23:47:40.333930 containerd[1520]: time="2025-10-30T23:47:40.333907520Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 30 23:47:40.334026 containerd[1520]: time="2025-10-30T23:47:40.334006360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 30 23:47:40.334051 containerd[1520]: time="2025-10-30T23:47:40.334041880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 30 23:47:40.334069 containerd[1520]: time="2025-10-30T23:47:40.334058080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 30 23:47:40.334087 containerd[1520]: time="2025-10-30T23:47:40.334068520Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 30 23:47:40.334087 containerd[1520]: time="2025-10-30T23:47:40.334079880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 30 23:47:40.334122 containerd[1520]: time="2025-10-30T23:47:40.334090360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 30 23:47:40.334122 containerd[1520]: time="2025-10-30T23:47:40.334103760Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 30 23:47:40.334158 containerd[1520]: time="2025-10-30T23:47:40.334127760Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 30 23:47:40.334158 containerd[1520]: time="2025-10-30T23:47:40.334143920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 30 23:47:40.334158 containerd[1520]: time="2025-10-30T23:47:40.334154920Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 30 23:47:40.334205 containerd[1520]: time="2025-10-30T23:47:40.334192680Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 30 23:47:40.334223 containerd[1520]: time="2025-10-30T23:47:40.334208160Z" 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 30 23:47:40.334223 containerd[1520]: time="2025-10-30T23:47:40.334217240Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 30 23:47:40.334258 containerd[1520]: time="2025-10-30T23:47:40.334226920Z" 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 30 23:47:40.334258 containerd[1520]: time="2025-10-30T23:47:40.334234800Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 30 23:47:40.334258 containerd[1520]: time="2025-10-30T23:47:40.334244400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 30 23:47:40.334258 containerd[1520]: time="2025-10-30T23:47:40.334254640Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 30 23:47:40.334366 containerd[1520]: time="2025-10-30T23:47:40.334337400Z" level=info msg="runtime interface created" Oct 30 23:47:40.334366 containerd[1520]: time="2025-10-30T23:47:40.334362600Z" level=info msg="created NRI interface" Oct 30 23:47:40.334408 containerd[1520]: time="2025-10-30T23:47:40.334379600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 30 23:47:40.334408 containerd[1520]: time="2025-10-30T23:47:40.334390840Z" level=info msg="Connect containerd service" Oct 30 23:47:40.334440 containerd[1520]: time="2025-10-30T23:47:40.334416360Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 30 23:47:40.335208 containerd[1520]: time="2025-10-30T23:47:40.335168720Z" 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 30 23:47:40.403639 containerd[1520]: time="2025-10-30T23:47:40.403503120Z" level=info msg="Start subscribing containerd event" Oct 30 23:47:40.403639 containerd[1520]: time="2025-10-30T23:47:40.403599640Z" level=info msg="Start recovering state" Oct 30 23:47:40.403889 containerd[1520]: time="2025-10-30T23:47:40.403858800Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 30 23:47:40.404009 containerd[1520]: time="2025-10-30T23:47:40.403925080Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 30 23:47:40.404009 containerd[1520]: time="2025-10-30T23:47:40.403868040Z" level=info msg="Start event monitor" Oct 30 23:47:40.404009 containerd[1520]: time="2025-10-30T23:47:40.403972440Z" level=info msg="Start cni network conf syncer for default" Oct 30 23:47:40.404009 containerd[1520]: time="2025-10-30T23:47:40.403982280Z" level=info msg="Start streaming server" Oct 30 23:47:40.404105 containerd[1520]: time="2025-10-30T23:47:40.403994880Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 30 23:47:40.404148 containerd[1520]: time="2025-10-30T23:47:40.404136640Z" level=info msg="runtime interface starting up..." Oct 30 23:47:40.404273 containerd[1520]: time="2025-10-30T23:47:40.404184920Z" level=info msg="starting plugins..." Oct 30 23:47:40.404273 containerd[1520]: time="2025-10-30T23:47:40.404205000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 30 23:47:40.404443 containerd[1520]: time="2025-10-30T23:47:40.404430640Z" level=info msg="containerd successfully booted in 0.088355s" Oct 30 23:47:40.404550 systemd[1]: Started containerd.service - containerd container runtime. Oct 30 23:47:40.969041 sshd_keygen[1515]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 30 23:47:40.988029 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 30 23:47:40.991479 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 30 23:47:41.011086 systemd[1]: issuegen.service: Deactivated successfully. Oct 30 23:47:41.011308 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 30 23:47:41.014311 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 30 23:47:41.044517 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 30 23:47:41.047290 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 30 23:47:41.049413 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 30 23:47:41.050791 systemd[1]: Reached target getty.target - Login Prompts. Oct 30 23:47:41.768835 systemd-networkd[1446]: eth0: Gained IPv6LL Oct 30 23:47:41.771803 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 30 23:47:41.773559 systemd[1]: Reached target network-online.target - Network is Online. Oct 30 23:47:41.780649 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 30 23:47:41.783811 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 30 23:47:41.793894 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 30 23:47:41.809249 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 30 23:47:41.810588 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 30 23:47:41.814455 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 30 23:47:41.818330 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 30 23:47:42.337407 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 30 23:47:42.340102 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 30 23:47:42.341816 (kubelet)[1624]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 30 23:47:42.347053 systemd[1]: Startup finished in 2.071s (kernel) + 4.786s (initrd) + 3.991s (userspace) = 10.849s. Oct 30 23:47:42.682411 kubelet[1624]: E1030 23:47:42.682282 1624 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 30 23:47:42.684629 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 30 23:47:42.684954 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 30 23:47:42.685273 systemd[1]: kubelet.service: Consumed 751ms CPU time, 257.7M memory peak. Oct 30 23:47:46.551067 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 30 23:47:46.552105 systemd[1]: Started sshd@0-10.0.0.44:22-10.0.0.1:42006.service - OpenSSH per-connection server daemon (10.0.0.1:42006). Oct 30 23:47:46.631853 sshd[1637]: Accepted publickey for core from 10.0.0.1 port 42006 ssh2: RSA SHA256:eMT2yr5isfZFKgr4u+rPHefcjXBjXGBc9p91goGyQfE Oct 30 23:47:46.635139 sshd-session[1637]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 23:47:46.641934 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 30 23:47:46.642805 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 30 23:47:46.647968 systemd-logind[1498]: New session 1 of user core. Oct 30 23:47:46.667855 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 30 23:47:46.670428 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 30 23:47:46.691878 (systemd)[1642]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 30 23:47:46.694865 systemd-logind[1498]: New session c1 of user core. Oct 30 23:47:46.803846 systemd[1642]: Queued start job for default target default.target. Oct 30 23:47:46.822860 systemd[1642]: Created slice app.slice - User Application Slice. Oct 30 23:47:46.822895 systemd[1642]: Reached target paths.target - Paths. Oct 30 23:47:46.822934 systemd[1642]: Reached target timers.target - Timers. Oct 30 23:47:46.824120 systemd[1642]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 30 23:47:46.837056 systemd[1642]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 30 23:47:46.837289 systemd[1642]: Reached target sockets.target - Sockets. Oct 30 23:47:46.837434 systemd[1642]: Reached target basic.target - Basic System. Oct 30 23:47:46.837550 systemd[1642]: Reached target default.target - Main User Target. Oct 30 23:47:46.837591 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 30 23:47:46.837686 systemd[1642]: Startup finished in 136ms. Oct 30 23:47:46.838819 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 30 23:47:46.898844 systemd[1]: Started sshd@1-10.0.0.44:22-10.0.0.1:42012.service - OpenSSH per-connection server daemon (10.0.0.1:42012). Oct 30 23:47:46.975117 sshd[1653]: Accepted publickey for core from 10.0.0.1 port 42012 ssh2: RSA SHA256:eMT2yr5isfZFKgr4u+rPHefcjXBjXGBc9p91goGyQfE Oct 30 23:47:46.976492 sshd-session[1653]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 23:47:46.981334 systemd-logind[1498]: New session 2 of user core. Oct 30 23:47:46.992957 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 30 23:47:47.051101 sshd[1656]: Connection closed by 10.0.0.1 port 42012 Oct 30 23:47:47.051422 sshd-session[1653]: pam_unix(sshd:session): session closed for user core Oct 30 23:47:47.063814 systemd[1]: sshd@1-10.0.0.44:22-10.0.0.1:42012.service: Deactivated successfully. Oct 30 23:47:47.065336 systemd[1]: session-2.scope: Deactivated successfully. Oct 30 23:47:47.067808 systemd-logind[1498]: Session 2 logged out. Waiting for processes to exit. Oct 30 23:47:47.069943 systemd[1]: Started sshd@2-10.0.0.44:22-10.0.0.1:42018.service - OpenSSH per-connection server daemon (10.0.0.1:42018). Oct 30 23:47:47.073752 systemd-logind[1498]: Removed session 2. Oct 30 23:47:47.128855 sshd[1662]: Accepted publickey for core from 10.0.0.1 port 42018 ssh2: RSA SHA256:eMT2yr5isfZFKgr4u+rPHefcjXBjXGBc9p91goGyQfE Oct 30 23:47:47.130169 sshd-session[1662]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 23:47:47.134791 systemd-logind[1498]: New session 3 of user core. Oct 30 23:47:47.141898 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 30 23:47:47.189381 sshd[1665]: Connection closed by 10.0.0.1 port 42018 Oct 30 23:47:47.189895 sshd-session[1662]: pam_unix(sshd:session): session closed for user core Oct 30 23:47:47.199710 systemd[1]: sshd@2-10.0.0.44:22-10.0.0.1:42018.service: Deactivated successfully. Oct 30 23:47:47.203766 systemd[1]: session-3.scope: Deactivated successfully. Oct 30 23:47:47.204561 systemd-logind[1498]: Session 3 logged out. Waiting for processes to exit. Oct 30 23:47:47.207091 systemd[1]: Started sshd@3-10.0.0.44:22-10.0.0.1:42034.service - OpenSSH per-connection server daemon (10.0.0.1:42034). Oct 30 23:47:47.208148 systemd-logind[1498]: Removed session 3. Oct 30 23:47:47.271807 sshd[1671]: Accepted publickey for core from 10.0.0.1 port 42034 ssh2: RSA SHA256:eMT2yr5isfZFKgr4u+rPHefcjXBjXGBc9p91goGyQfE Oct 30 23:47:47.273455 sshd-session[1671]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 23:47:47.279153 systemd-logind[1498]: New session 4 of user core. Oct 30 23:47:47.290900 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 30 23:47:47.343642 sshd[1674]: Connection closed by 10.0.0.1 port 42034 Oct 30 23:47:47.344467 sshd-session[1671]: pam_unix(sshd:session): session closed for user core Oct 30 23:47:47.355041 systemd[1]: sshd@3-10.0.0.44:22-10.0.0.1:42034.service: Deactivated successfully. Oct 30 23:47:47.357127 systemd[1]: session-4.scope: Deactivated successfully. Oct 30 23:47:47.357835 systemd-logind[1498]: Session 4 logged out. Waiting for processes to exit. Oct 30 23:47:47.360061 systemd[1]: Started sshd@4-10.0.0.44:22-10.0.0.1:42050.service - OpenSSH per-connection server daemon (10.0.0.1:42050). Oct 30 23:47:47.361020 systemd-logind[1498]: Removed session 4. Oct 30 23:47:47.414030 sshd[1680]: Accepted publickey for core from 10.0.0.1 port 42050 ssh2: RSA SHA256:eMT2yr5isfZFKgr4u+rPHefcjXBjXGBc9p91goGyQfE Oct 30 23:47:47.416830 sshd-session[1680]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 23:47:47.423775 systemd-logind[1498]: New session 5 of user core. Oct 30 23:47:47.434900 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 30 23:47:47.492957 sudo[1685]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 30 23:47:47.493208 sudo[1685]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 30 23:47:47.504201 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 30 23:47:47.539948 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 30 23:47:47.540795 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 30 23:47:48.033578 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 30 23:47:48.033740 systemd[1]: kubelet.service: Consumed 751ms CPU time, 257.7M memory peak. Oct 30 23:47:48.035671 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 30 23:47:48.059119 systemd[1]: Reload requested from client PID 1728 ('systemctl') (unit session-5.scope)... Oct 30 23:47:48.059136 systemd[1]: Reloading... Oct 30 23:47:48.119727 zram_generator::config[1769]: No configuration found. Oct 30 23:47:48.384906 systemd[1]: Reloading finished in 325 ms. Oct 30 23:47:48.446272 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 30 23:47:48.446357 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 30 23:47:48.446588 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 30 23:47:48.446634 systemd[1]: kubelet.service: Consumed 102ms CPU time, 95.1M memory peak. Oct 30 23:47:48.448138 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 30 23:47:48.573268 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 30 23:47:48.577441 (kubelet)[1814]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 30 23:47:48.611627 kubelet[1814]: 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 30 23:47:48.611627 kubelet[1814]: 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 30 23:47:48.611627 kubelet[1814]: 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 30 23:47:48.611970 kubelet[1814]: I1030 23:47:48.611672 1814 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 30 23:47:49.215833 kubelet[1814]: I1030 23:47:49.215796 1814 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Oct 30 23:47:49.215833 kubelet[1814]: I1030 23:47:49.215826 1814 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 30 23:47:49.216064 kubelet[1814]: I1030 23:47:49.216047 1814 server.go:956] "Client rotation is on, will bootstrap in background" Oct 30 23:47:49.238338 kubelet[1814]: I1030 23:47:49.236304 1814 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 30 23:47:49.254285 kubelet[1814]: I1030 23:47:49.254247 1814 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 30 23:47:49.261909 kubelet[1814]: I1030 23:47:49.259104 1814 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 30 23:47:49.261909 kubelet[1814]: I1030 23:47:49.259387 1814 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 30 23:47:49.261909 kubelet[1814]: I1030 23:47:49.259412 1814 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.44","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 30 23:47:49.261909 kubelet[1814]: I1030 23:47:49.259687 1814 topology_manager.go:138] "Creating topology manager with none policy" Oct 30 23:47:49.262152 kubelet[1814]: I1030 23:47:49.259723 1814 container_manager_linux.go:303] "Creating device plugin manager" Oct 30 23:47:49.262152 kubelet[1814]: I1030 23:47:49.259936 1814 state_mem.go:36] "Initialized new in-memory state store" Oct 30 23:47:49.262725 kubelet[1814]: I1030 23:47:49.262416 1814 kubelet.go:480] "Attempting to sync node with API server" Oct 30 23:47:49.262725 kubelet[1814]: I1030 23:47:49.262500 1814 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 30 23:47:49.262725 kubelet[1814]: I1030 23:47:49.262537 1814 kubelet.go:386] "Adding apiserver pod source" Oct 30 23:47:49.263638 kubelet[1814]: I1030 23:47:49.263617 1814 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 30 23:47:49.263679 kubelet[1814]: E1030 23:47:49.263658 1814 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:49.263751 kubelet[1814]: E1030 23:47:49.263731 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:49.264836 kubelet[1814]: I1030 23:47:49.264800 1814 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Oct 30 23:47:49.265743 kubelet[1814]: I1030 23:47:49.265696 1814 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 30 23:47:49.265865 kubelet[1814]: W1030 23:47:49.265842 1814 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 30 23:47:49.268277 kubelet[1814]: I1030 23:47:49.268243 1814 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 30 23:47:49.268329 kubelet[1814]: I1030 23:47:49.268294 1814 server.go:1289] "Started kubelet" Oct 30 23:47:49.269502 kubelet[1814]: I1030 23:47:49.269465 1814 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 30 23:47:49.270098 kubelet[1814]: I1030 23:47:49.269862 1814 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 30 23:47:49.270445 kubelet[1814]: I1030 23:47:49.270425 1814 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 30 23:47:49.270559 kubelet[1814]: I1030 23:47:49.270541 1814 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Oct 30 23:47:49.271618 kubelet[1814]: I1030 23:47:49.271597 1814 server.go:317] "Adding debug handlers to kubelet server" Oct 30 23:47:49.272718 kubelet[1814]: I1030 23:47:49.272666 1814 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 30 23:47:49.273962 kubelet[1814]: E1030 23:47:49.273927 1814 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 30 23:47:49.274014 kubelet[1814]: I1030 23:47:49.273973 1814 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 30 23:47:49.274241 kubelet[1814]: I1030 23:47:49.274216 1814 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 30 23:47:49.274314 kubelet[1814]: I1030 23:47:49.274299 1814 reconciler.go:26] "Reconciler: start to sync state" Oct 30 23:47:49.274451 kubelet[1814]: E1030 23:47:49.274426 1814 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 30 23:47:49.275879 kubelet[1814]: I1030 23:47:49.275823 1814 factory.go:223] Registration of the containerd container factory successfully Oct 30 23:47:49.275879 kubelet[1814]: I1030 23:47:49.275851 1814 factory.go:223] Registration of the systemd container factory successfully Oct 30 23:47:49.275992 kubelet[1814]: I1030 23:47:49.275946 1814 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 30 23:47:49.282425 kubelet[1814]: E1030 23:47:49.282247 1814 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.44\" not found" node="10.0.0.44" Oct 30 23:47:49.289378 kubelet[1814]: I1030 23:47:49.289337 1814 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 30 23:47:49.289378 kubelet[1814]: I1030 23:47:49.289363 1814 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 30 23:47:49.289378 kubelet[1814]: I1030 23:47:49.289384 1814 state_mem.go:36] "Initialized new in-memory state store" Oct 30 23:47:49.374816 kubelet[1814]: E1030 23:47:49.374743 1814 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 30 23:47:49.392719 kubelet[1814]: I1030 23:47:49.392541 1814 policy_none.go:49] "None policy: Start" Oct 30 23:47:49.392719 kubelet[1814]: I1030 23:47:49.392567 1814 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 30 23:47:49.392719 kubelet[1814]: I1030 23:47:49.392579 1814 state_mem.go:35] "Initializing new in-memory state store" Oct 30 23:47:49.405001 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 30 23:47:49.418948 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 30 23:47:49.423475 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 30 23:47:49.426602 kubelet[1814]: I1030 23:47:49.426553 1814 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Oct 30 23:47:49.427524 kubelet[1814]: I1030 23:47:49.427489 1814 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Oct 30 23:47:49.427524 kubelet[1814]: I1030 23:47:49.427511 1814 status_manager.go:230] "Starting to sync pod status with apiserver" Oct 30 23:47:49.427524 kubelet[1814]: I1030 23:47:49.427527 1814 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 30 23:47:49.427625 kubelet[1814]: I1030 23:47:49.427533 1814 kubelet.go:2436] "Starting kubelet main sync loop" Oct 30 23:47:49.427625 kubelet[1814]: E1030 23:47:49.427570 1814 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 30 23:47:49.431842 kubelet[1814]: E1030 23:47:49.431770 1814 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 30 23:47:49.431989 kubelet[1814]: I1030 23:47:49.431973 1814 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 30 23:47:49.432019 kubelet[1814]: I1030 23:47:49.431990 1814 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 30 23:47:49.432289 kubelet[1814]: I1030 23:47:49.432275 1814 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 30 23:47:49.432927 kubelet[1814]: E1030 23:47:49.432884 1814 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 30 23:47:49.433531 kubelet[1814]: E1030 23:47:49.433480 1814 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.44\" not found" Oct 30 23:47:49.533639 kubelet[1814]: I1030 23:47:49.533110 1814 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.44" Oct 30 23:47:49.540633 kubelet[1814]: I1030 23:47:49.540582 1814 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.44" Oct 30 23:47:49.603454 sudo[1685]: pam_unix(sudo:session): session closed for user root Oct 30 23:47:49.604793 sshd[1684]: Connection closed by 10.0.0.1 port 42050 Oct 30 23:47:49.605203 sshd-session[1680]: pam_unix(sshd:session): session closed for user core Oct 30 23:47:49.609005 systemd[1]: sshd@4-10.0.0.44:22-10.0.0.1:42050.service: Deactivated successfully. Oct 30 23:47:49.611093 systemd[1]: session-5.scope: Deactivated successfully. Oct 30 23:47:49.611316 systemd[1]: session-5.scope: Consumed 429ms CPU time, 75.2M memory peak. Oct 30 23:47:49.613067 systemd-logind[1498]: Session 5 logged out. Waiting for processes to exit. Oct 30 23:47:49.614281 systemd-logind[1498]: Removed session 5. Oct 30 23:47:49.650486 kubelet[1814]: I1030 23:47:49.650449 1814 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 30 23:47:49.650859 containerd[1520]: time="2025-10-30T23:47:49.650717827Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 30 23:47:49.651030 kubelet[1814]: I1030 23:47:49.651012 1814 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 30 23:47:50.217992 kubelet[1814]: I1030 23:47:50.217684 1814 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 30 23:47:50.217992 kubelet[1814]: I1030 23:47:50.217933 1814 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 30 23:47:50.218306 kubelet[1814]: I1030 23:47:50.218230 1814 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 30 23:47:50.218306 kubelet[1814]: I1030 23:47:50.218279 1814 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 30 23:47:50.264495 kubelet[1814]: E1030 23:47:50.264455 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:50.264495 kubelet[1814]: I1030 23:47:50.264482 1814 apiserver.go:52] "Watching apiserver" Oct 30 23:47:50.275265 kubelet[1814]: I1030 23:47:50.275213 1814 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 30 23:47:50.278617 kubelet[1814]: I1030 23:47:50.278581 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/78ebd901-71c2-4879-8dd2-9a189294126f-cni-plugin\") pod \"kube-flannel-ds-9ds4h\" (UID: \"78ebd901-71c2-4879-8dd2-9a189294126f\") " pod="kube-flannel/kube-flannel-ds-9ds4h" Oct 30 23:47:50.278617 kubelet[1814]: I1030 23:47:50.278610 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/78ebd901-71c2-4879-8dd2-9a189294126f-cni\") pod \"kube-flannel-ds-9ds4h\" (UID: \"78ebd901-71c2-4879-8dd2-9a189294126f\") " pod="kube-flannel/kube-flannel-ds-9ds4h" Oct 30 23:47:50.278716 kubelet[1814]: I1030 23:47:50.278644 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/78ebd901-71c2-4879-8dd2-9a189294126f-xtables-lock\") pod \"kube-flannel-ds-9ds4h\" (UID: \"78ebd901-71c2-4879-8dd2-9a189294126f\") " pod="kube-flannel/kube-flannel-ds-9ds4h" Oct 30 23:47:50.278716 kubelet[1814]: I1030 23:47:50.278666 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2e764fe4-710c-4340-8e4b-a79777a29a87-xtables-lock\") pod \"kube-proxy-sxlnx\" (UID: \"2e764fe4-710c-4340-8e4b-a79777a29a87\") " pod="kube-system/kube-proxy-sxlnx" Oct 30 23:47:50.278716 kubelet[1814]: I1030 23:47:50.278682 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sffjf\" (UniqueName: \"kubernetes.io/projected/2e764fe4-710c-4340-8e4b-a79777a29a87-kube-api-access-sffjf\") pod \"kube-proxy-sxlnx\" (UID: \"2e764fe4-710c-4340-8e4b-a79777a29a87\") " pod="kube-system/kube-proxy-sxlnx" Oct 30 23:47:50.278779 kubelet[1814]: I1030 23:47:50.278716 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/78ebd901-71c2-4879-8dd2-9a189294126f-run\") pod \"kube-flannel-ds-9ds4h\" (UID: \"78ebd901-71c2-4879-8dd2-9a189294126f\") " pod="kube-flannel/kube-flannel-ds-9ds4h" Oct 30 23:47:50.278779 kubelet[1814]: I1030 23:47:50.278741 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/78ebd901-71c2-4879-8dd2-9a189294126f-flannel-cfg\") pod \"kube-flannel-ds-9ds4h\" (UID: \"78ebd901-71c2-4879-8dd2-9a189294126f\") " pod="kube-flannel/kube-flannel-ds-9ds4h" Oct 30 23:47:50.278779 kubelet[1814]: I1030 23:47:50.278761 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9m22x\" (UniqueName: \"kubernetes.io/projected/78ebd901-71c2-4879-8dd2-9a189294126f-kube-api-access-9m22x\") pod \"kube-flannel-ds-9ds4h\" (UID: \"78ebd901-71c2-4879-8dd2-9a189294126f\") " pod="kube-flannel/kube-flannel-ds-9ds4h" Oct 30 23:47:50.278779 kubelet[1814]: I1030 23:47:50.278774 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/2e764fe4-710c-4340-8e4b-a79777a29a87-kube-proxy\") pod \"kube-proxy-sxlnx\" (UID: \"2e764fe4-710c-4340-8e4b-a79777a29a87\") " pod="kube-system/kube-proxy-sxlnx" Oct 30 23:47:50.278873 kubelet[1814]: I1030 23:47:50.278787 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/2e764fe4-710c-4340-8e4b-a79777a29a87-lib-modules\") pod \"kube-proxy-sxlnx\" (UID: \"2e764fe4-710c-4340-8e4b-a79777a29a87\") " pod="kube-system/kube-proxy-sxlnx" Oct 30 23:47:50.280289 systemd[1]: Created slice kubepods-burstable-pod78ebd901_71c2_4879_8dd2_9a189294126f.slice - libcontainer container kubepods-burstable-pod78ebd901_71c2_4879_8dd2_9a189294126f.slice. Oct 30 23:47:50.302770 systemd[1]: Created slice kubepods-besteffort-pod2e764fe4_710c_4340_8e4b_a79777a29a87.slice - libcontainer container kubepods-besteffort-pod2e764fe4_710c_4340_8e4b_a79777a29a87.slice. Oct 30 23:47:50.601419 containerd[1520]: time="2025-10-30T23:47:50.601300253Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9ds4h,Uid:78ebd901-71c2-4879-8dd2-9a189294126f,Namespace:kube-flannel,Attempt:0,}" Oct 30 23:47:50.613038 containerd[1520]: time="2025-10-30T23:47:50.612986430Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-sxlnx,Uid:2e764fe4-710c-4340-8e4b-a79777a29a87,Namespace:kube-system,Attempt:0,}" Oct 30 23:47:51.208322 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3194200761.mount: Deactivated successfully. Oct 30 23:47:51.216965 containerd[1520]: time="2025-10-30T23:47:51.216465050Z" 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 30 23:47:51.220231 containerd[1520]: time="2025-10-30T23:47:51.220155668Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Oct 30 23:47:51.223878 containerd[1520]: time="2025-10-30T23:47:51.223098724Z" 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 30 23:47:51.224756 containerd[1520]: time="2025-10-30T23:47:51.224677487Z" 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 30 23:47:51.225204 containerd[1520]: time="2025-10-30T23:47:51.225154650Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 30 23:47:51.231284 containerd[1520]: time="2025-10-30T23:47:51.231223887Z" 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 30 23:47:51.231929 containerd[1520]: time="2025-10-30T23:47:51.231655911Z" 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 624.518199ms" Oct 30 23:47:51.233736 containerd[1520]: time="2025-10-30T23:47:51.233686026Z" 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 616.236245ms" Oct 30 23:47:51.263455 containerd[1520]: time="2025-10-30T23:47:51.263405403Z" level=info msg="connecting to shim bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82" address="unix:///run/containerd/s/883336776f26e35f3ce91d15fdfb9ef6a48b412a8d99c468793dc18a9076a25c" namespace=k8s.io protocol=ttrpc version=3 Oct 30 23:47:51.264599 kubelet[1814]: E1030 23:47:51.264567 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:51.268691 containerd[1520]: time="2025-10-30T23:47:51.268644982Z" level=info msg="connecting to shim ac4691f895b72c75146a1f52b8c6984f60243953beb9df61c174d0e0af1e18dc" address="unix:///run/containerd/s/c5bdca4a4a5bc919aee6761bbd6fb837cc64530deadf1fe8ac7ddfd874b09141" namespace=k8s.io protocol=ttrpc version=3 Oct 30 23:47:51.287959 systemd[1]: Started cri-containerd-bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82.scope - libcontainer container bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82. Oct 30 23:47:51.294493 systemd[1]: Started cri-containerd-ac4691f895b72c75146a1f52b8c6984f60243953beb9df61c174d0e0af1e18dc.scope - libcontainer container ac4691f895b72c75146a1f52b8c6984f60243953beb9df61c174d0e0af1e18dc. Oct 30 23:47:51.327126 containerd[1520]: time="2025-10-30T23:47:51.327080083Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-sxlnx,Uid:2e764fe4-710c-4340-8e4b-a79777a29a87,Namespace:kube-system,Attempt:0,} returns sandbox id \"ac4691f895b72c75146a1f52b8c6984f60243953beb9df61c174d0e0af1e18dc\"" Oct 30 23:47:51.331101 containerd[1520]: time="2025-10-30T23:47:51.331069007Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Oct 30 23:47:51.335141 containerd[1520]: time="2025-10-30T23:47:51.335112636Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-9ds4h,Uid:78ebd901-71c2-4879-8dd2-9a189294126f,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82\"" Oct 30 23:47:52.265490 kubelet[1814]: E1030 23:47:52.265438 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:52.292955 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1639483034.mount: Deactivated successfully. Oct 30 23:47:52.541284 containerd[1520]: time="2025-10-30T23:47:52.541157134Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:52.542157 containerd[1520]: time="2025-10-30T23:47:52.542121994Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240108" Oct 30 23:47:52.543039 containerd[1520]: time="2025-10-30T23:47:52.543011284Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:52.546270 containerd[1520]: time="2025-10-30T23:47:52.546233670Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:52.547133 containerd[1520]: time="2025-10-30T23:47:52.547099684Z" 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.215875565s" Oct 30 23:47:52.547188 containerd[1520]: time="2025-10-30T23:47:52.547137132Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Oct 30 23:47:52.548850 containerd[1520]: time="2025-10-30T23:47:52.548789592Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 30 23:47:52.552330 containerd[1520]: time="2025-10-30T23:47:52.552286583Z" level=info msg="CreateContainer within sandbox \"ac4691f895b72c75146a1f52b8c6984f60243953beb9df61c174d0e0af1e18dc\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 30 23:47:52.603398 containerd[1520]: time="2025-10-30T23:47:52.602199104Z" level=info msg="Container d6f34d118ca0ebe9e46b493cbc6c2dcec75d112a3fca2bf3d3ebf2c6bead79bc: CDI devices from CRI Config.CDIDevices: []" Oct 30 23:47:52.691113 containerd[1520]: time="2025-10-30T23:47:52.691052167Z" level=info msg="CreateContainer within sandbox \"ac4691f895b72c75146a1f52b8c6984f60243953beb9df61c174d0e0af1e18dc\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"d6f34d118ca0ebe9e46b493cbc6c2dcec75d112a3fca2bf3d3ebf2c6bead79bc\"" Oct 30 23:47:52.692278 containerd[1520]: time="2025-10-30T23:47:52.692158426Z" level=info msg="StartContainer for \"d6f34d118ca0ebe9e46b493cbc6c2dcec75d112a3fca2bf3d3ebf2c6bead79bc\"" Oct 30 23:47:52.693676 containerd[1520]: time="2025-10-30T23:47:52.693650684Z" level=info msg="connecting to shim d6f34d118ca0ebe9e46b493cbc6c2dcec75d112a3fca2bf3d3ebf2c6bead79bc" address="unix:///run/containerd/s/c5bdca4a4a5bc919aee6761bbd6fb837cc64530deadf1fe8ac7ddfd874b09141" protocol=ttrpc version=3 Oct 30 23:47:52.727926 systemd[1]: Started cri-containerd-d6f34d118ca0ebe9e46b493cbc6c2dcec75d112a3fca2bf3d3ebf2c6bead79bc.scope - libcontainer container d6f34d118ca0ebe9e46b493cbc6c2dcec75d112a3fca2bf3d3ebf2c6bead79bc. Oct 30 23:47:52.783624 containerd[1520]: time="2025-10-30T23:47:52.783578377Z" level=info msg="StartContainer for \"d6f34d118ca0ebe9e46b493cbc6c2dcec75d112a3fca2bf3d3ebf2c6bead79bc\" returns successfully" Oct 30 23:47:53.265669 kubelet[1814]: E1030 23:47:53.265614 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:53.644434 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2309902778.mount: Deactivated successfully. Oct 30 23:47:53.686203 containerd[1520]: time="2025-10-30T23:47:53.686144641Z" 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 30 23:47:53.686839 containerd[1520]: time="2025-10-30T23:47:53.686814592Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Oct 30 23:47:53.687670 containerd[1520]: time="2025-10-30T23:47:53.687622798Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:53.690569 containerd[1520]: time="2025-10-30T23:47:53.690043216Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:53.691887 containerd[1520]: time="2025-10-30T23:47:53.691859401Z" 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 1.143031676s" Oct 30 23:47:53.691973 containerd[1520]: time="2025-10-30T23:47:53.691956711Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 30 23:47:53.695477 containerd[1520]: time="2025-10-30T23:47:53.695448329Z" level=info msg="CreateContainer within sandbox \"bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 30 23:47:53.705781 containerd[1520]: time="2025-10-30T23:47:53.705742794Z" level=info msg="Container e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba: CDI devices from CRI Config.CDIDevices: []" Oct 30 23:47:53.712734 containerd[1520]: time="2025-10-30T23:47:53.712670934Z" level=info msg="CreateContainer within sandbox \"bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba\"" Oct 30 23:47:53.713514 containerd[1520]: time="2025-10-30T23:47:53.713473155Z" level=info msg="StartContainer for \"e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba\"" Oct 30 23:47:53.714244 containerd[1520]: time="2025-10-30T23:47:53.714206526Z" level=info msg="connecting to shim e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba" address="unix:///run/containerd/s/883336776f26e35f3ce91d15fdfb9ef6a48b412a8d99c468793dc18a9076a25c" protocol=ttrpc version=3 Oct 30 23:47:53.732904 systemd[1]: Started cri-containerd-e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba.scope - libcontainer container e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba. Oct 30 23:47:53.756293 systemd[1]: cri-containerd-e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba.scope: Deactivated successfully. Oct 30 23:47:53.758106 containerd[1520]: time="2025-10-30T23:47:53.758050277Z" level=info msg="TaskExit event in podsandbox handler container_id:\"e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba\" id:\"e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba\" pid:2158 exited_at:{seconds:1761868073 nanos:757540382}" Oct 30 23:47:53.758237 containerd[1520]: time="2025-10-30T23:47:53.758208074Z" level=info msg="received exit event container_id:\"e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba\" id:\"e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba\" pid:2158 exited_at:{seconds:1761868073 nanos:757540382}" Oct 30 23:47:53.759548 containerd[1520]: time="2025-10-30T23:47:53.759502195Z" level=info msg="StartContainer for \"e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba\" returns successfully" Oct 30 23:47:54.266712 kubelet[1814]: E1030 23:47:54.266653 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:54.442277 containerd[1520]: time="2025-10-30T23:47:54.442187495Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 30 23:47:54.457947 kubelet[1814]: I1030 23:47:54.457864 1814 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-sxlnx" podStartSLOduration=4.240598835 podStartE2EDuration="5.457841811s" podCreationTimestamp="2025-10-30 23:47:49 +0000 UTC" firstStartedPulling="2025-10-30 23:47:51.330772124 +0000 UTC m=+2.749441381" lastFinishedPulling="2025-10-30 23:47:52.54801514 +0000 UTC m=+3.966684357" observedRunningTime="2025-10-30 23:47:53.447726922 +0000 UTC m=+4.866396178" watchObservedRunningTime="2025-10-30 23:47:54.457841811 +0000 UTC m=+5.876511068" Oct 30 23:47:54.644432 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e9088825ccbdd51290d4b0b98593ebd73a5c52fca2ffd6d049b619a5cfed4bba-rootfs.mount: Deactivated successfully. Oct 30 23:47:55.266913 kubelet[1814]: E1030 23:47:55.266879 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:55.742486 containerd[1520]: time="2025-10-30T23:47:55.742347162Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:55.742994 containerd[1520]: time="2025-10-30T23:47:55.742941091Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Oct 30 23:47:55.744162 containerd[1520]: time="2025-10-30T23:47:55.744129386Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:55.747869 containerd[1520]: time="2025-10-30T23:47:55.747826904Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:47:55.749675 containerd[1520]: time="2025-10-30T23:47:55.749633716Z" 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.307375102s" Oct 30 23:47:55.749675 containerd[1520]: time="2025-10-30T23:47:55.749671651Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 30 23:47:55.752805 containerd[1520]: time="2025-10-30T23:47:55.752742787Z" level=info msg="CreateContainer within sandbox \"bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 30 23:47:55.761359 containerd[1520]: time="2025-10-30T23:47:55.761306653Z" level=info msg="Container f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7: CDI devices from CRI Config.CDIDevices: []" Oct 30 23:47:55.767788 containerd[1520]: time="2025-10-30T23:47:55.767749822Z" level=info msg="CreateContainer within sandbox \"bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7\"" Oct 30 23:47:55.768222 containerd[1520]: time="2025-10-30T23:47:55.768198406Z" level=info msg="StartContainer for \"f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7\"" Oct 30 23:47:55.768985 containerd[1520]: time="2025-10-30T23:47:55.768937004Z" level=info msg="connecting to shim f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7" address="unix:///run/containerd/s/883336776f26e35f3ce91d15fdfb9ef6a48b412a8d99c468793dc18a9076a25c" protocol=ttrpc version=3 Oct 30 23:47:55.786852 systemd[1]: Started cri-containerd-f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7.scope - libcontainer container f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7. Oct 30 23:47:55.813081 systemd[1]: cri-containerd-f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7.scope: Deactivated successfully. Oct 30 23:47:55.814397 containerd[1520]: time="2025-10-30T23:47:55.814364588Z" level=info msg="TaskExit event in podsandbox handler container_id:\"f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7\" id:\"f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7\" pid:2233 exited_at:{seconds:1761868075 nanos:814027543}" Oct 30 23:47:55.816395 containerd[1520]: time="2025-10-30T23:47:55.815985221Z" level=info msg="received exit event container_id:\"f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7\" id:\"f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7\" pid:2233 exited_at:{seconds:1761868075 nanos:814027543}" Oct 30 23:47:55.818739 containerd[1520]: time="2025-10-30T23:47:55.818685866Z" level=info msg="StartContainer for \"f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7\" returns successfully" Oct 30 23:47:55.836386 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-f9240e28ffcc620d0e062de0f5daedc3f8543d73552bfcc4fba64750c6167cc7-rootfs.mount: Deactivated successfully. Oct 30 23:47:55.868713 kubelet[1814]: I1030 23:47:55.868670 1814 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 30 23:47:56.268007 kubelet[1814]: E1030 23:47:56.267961 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:56.457986 containerd[1520]: time="2025-10-30T23:47:56.457923608Z" level=info msg="CreateContainer within sandbox \"bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 30 23:47:56.472601 containerd[1520]: time="2025-10-30T23:47:56.472535647Z" level=info msg="Container c2c118c3b0665ac2295eb5bca61121b9cbc1b0d6bc130e362f7e4d1ef5d81ac4: CDI devices from CRI Config.CDIDevices: []" Oct 30 23:47:56.484765 containerd[1520]: time="2025-10-30T23:47:56.484725605Z" level=info msg="CreateContainer within sandbox \"bed3ae3ee7a798b8516ce95044f0e66c59440e9bc2c866696cc9ff34c30bfb82\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"c2c118c3b0665ac2295eb5bca61121b9cbc1b0d6bc130e362f7e4d1ef5d81ac4\"" Oct 30 23:47:56.485748 containerd[1520]: time="2025-10-30T23:47:56.485421394Z" level=info msg="StartContainer for \"c2c118c3b0665ac2295eb5bca61121b9cbc1b0d6bc130e362f7e4d1ef5d81ac4\"" Oct 30 23:47:56.487774 containerd[1520]: time="2025-10-30T23:47:56.486277563Z" level=info msg="connecting to shim c2c118c3b0665ac2295eb5bca61121b9cbc1b0d6bc130e362f7e4d1ef5d81ac4" address="unix:///run/containerd/s/883336776f26e35f3ce91d15fdfb9ef6a48b412a8d99c468793dc18a9076a25c" protocol=ttrpc version=3 Oct 30 23:47:56.507782 systemd[1]: Started cri-containerd-c2c118c3b0665ac2295eb5bca61121b9cbc1b0d6bc130e362f7e4d1ef5d81ac4.scope - libcontainer container c2c118c3b0665ac2295eb5bca61121b9cbc1b0d6bc130e362f7e4d1ef5d81ac4. Oct 30 23:47:56.536497 containerd[1520]: time="2025-10-30T23:47:56.536372001Z" level=info msg="StartContainer for \"c2c118c3b0665ac2295eb5bca61121b9cbc1b0d6bc130e362f7e4d1ef5d81ac4\" returns successfully" Oct 30 23:47:57.268672 kubelet[1814]: E1030 23:47:57.268602 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:57.601291 systemd-networkd[1446]: flannel.1: Link UP Oct 30 23:47:57.601296 systemd-networkd[1446]: flannel.1: Gained carrier Oct 30 23:47:58.269464 kubelet[1814]: E1030 23:47:58.269392 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:47:58.920871 systemd-networkd[1446]: flannel.1: Gained IPv6LL Oct 30 23:47:59.270419 kubelet[1814]: E1030 23:47:59.270286 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:00.270467 kubelet[1814]: E1030 23:48:00.270411 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:00.415874 kubelet[1814]: I1030 23:48:00.415770 1814 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-9ds4h" podStartSLOduration=7.001607974 podStartE2EDuration="11.415745846s" podCreationTimestamp="2025-10-30 23:47:49 +0000 UTC" firstStartedPulling="2025-10-30 23:47:51.33616052 +0000 UTC m=+2.754829776" lastFinishedPulling="2025-10-30 23:47:55.750298391 +0000 UTC m=+7.168967648" observedRunningTime="2025-10-30 23:47:57.472263059 +0000 UTC m=+8.890932315" watchObservedRunningTime="2025-10-30 23:48:00.415745846 +0000 UTC m=+11.834415103" Oct 30 23:48:00.425421 systemd[1]: Created slice kubepods-besteffort-podc0f402be_9655_4d19_bf54_7f513374bc69.slice - libcontainer container kubepods-besteffort-podc0f402be_9655_4d19_bf54_7f513374bc69.slice. Oct 30 23:48:00.434954 kubelet[1814]: I1030 23:48:00.434896 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xwwcv\" (UniqueName: \"kubernetes.io/projected/c0f402be-9655-4d19-bf54-7f513374bc69-kube-api-access-xwwcv\") pod \"nginx-deployment-7fcdb87857-mkz44\" (UID: \"c0f402be-9655-4d19-bf54-7f513374bc69\") " pod="default/nginx-deployment-7fcdb87857-mkz44" Oct 30 23:48:00.728930 containerd[1520]: time="2025-10-30T23:48:00.728823587Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-mkz44,Uid:c0f402be-9655-4d19-bf54-7f513374bc69,Namespace:default,Attempt:0,}" Oct 30 23:48:00.749962 systemd-networkd[1446]: cni0: Link UP Oct 30 23:48:00.749966 systemd-networkd[1446]: cni0: Gained carrier Oct 30 23:48:00.756086 systemd-networkd[1446]: cni0: Lost carrier Oct 30 23:48:00.757894 systemd-networkd[1446]: veth68865696: Link UP Oct 30 23:48:00.760091 kernel: cni0: port 1(veth68865696) entered blocking state Oct 30 23:48:00.760150 kernel: cni0: port 1(veth68865696) entered disabled state Oct 30 23:48:00.760169 kernel: veth68865696: entered allmulticast mode Oct 30 23:48:00.762147 kernel: veth68865696: entered promiscuous mode Oct 30 23:48:00.770739 kernel: cni0: port 1(veth68865696) entered blocking state Oct 30 23:48:00.770861 kernel: cni0: port 1(veth68865696) entered forwarding state Oct 30 23:48:00.771620 systemd-networkd[1446]: veth68865696: Gained carrier Oct 30 23:48:00.772628 systemd-networkd[1446]: cni0: Gained carrier Oct 30 23:48:00.774111 containerd[1520]: 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 30 23:48:00.774111 containerd[1520]: delegateAdd: netconf sent to delegate plugin: Oct 30 23:48:00.803261 containerd[1520]: {"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-30T23:48:00.803212107Z" level=info msg="connecting to shim fa32e8b77c666960ea76ff202af66de02a65b645a73f0681fa3456901297f99f" address="unix:///run/containerd/s/f0895acb4cdc0da4588a489fdd540b7cf526791927762b6900577c154e6bd2cb" namespace=k8s.io protocol=ttrpc version=3 Oct 30 23:48:00.834892 systemd[1]: Started cri-containerd-fa32e8b77c666960ea76ff202af66de02a65b645a73f0681fa3456901297f99f.scope - libcontainer container fa32e8b77c666960ea76ff202af66de02a65b645a73f0681fa3456901297f99f. Oct 30 23:48:00.846054 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 30 23:48:00.868963 containerd[1520]: time="2025-10-30T23:48:00.868897321Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-mkz44,Uid:c0f402be-9655-4d19-bf54-7f513374bc69,Namespace:default,Attempt:0,} returns sandbox id \"fa32e8b77c666960ea76ff202af66de02a65b645a73f0681fa3456901297f99f\"" Oct 30 23:48:00.870645 containerd[1520]: time="2025-10-30T23:48:00.870613629Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 30 23:48:01.271431 kubelet[1814]: E1030 23:48:01.271379 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:02.057219 systemd-networkd[1446]: cni0: Gained IPv6LL Oct 30 23:48:02.272473 kubelet[1814]: E1030 23:48:02.272417 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:02.313255 systemd-networkd[1446]: veth68865696: Gained IPv6LL Oct 30 23:48:02.591951 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4141844540.mount: Deactivated successfully. Oct 30 23:48:03.273534 kubelet[1814]: E1030 23:48:03.273466 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:03.440447 containerd[1520]: time="2025-10-30T23:48:03.440396617Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:48:03.441321 containerd[1520]: time="2025-10-30T23:48:03.441177750Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Oct 30 23:48:03.442117 containerd[1520]: time="2025-10-30T23:48:03.442083863Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:48:03.444591 containerd[1520]: time="2025-10-30T23:48:03.444552841Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:48:03.446464 containerd[1520]: time="2025-10-30T23:48:03.446426482Z" 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.575569271s" Oct 30 23:48:03.446611 containerd[1520]: time="2025-10-30T23:48:03.446561320Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 30 23:48:03.449940 containerd[1520]: time="2025-10-30T23:48:03.449905765Z" level=info msg="CreateContainer within sandbox \"fa32e8b77c666960ea76ff202af66de02a65b645a73f0681fa3456901297f99f\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 30 23:48:03.461928 containerd[1520]: time="2025-10-30T23:48:03.460325815Z" level=info msg="Container 670f9eef4e68796a1a46be772cf8e4b385add7c6dc990fb0b894661bf35a28c8: CDI devices from CRI Config.CDIDevices: []" Oct 30 23:48:03.467976 containerd[1520]: time="2025-10-30T23:48:03.467907451Z" level=info msg="CreateContainer within sandbox \"fa32e8b77c666960ea76ff202af66de02a65b645a73f0681fa3456901297f99f\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"670f9eef4e68796a1a46be772cf8e4b385add7c6dc990fb0b894661bf35a28c8\"" Oct 30 23:48:03.468697 containerd[1520]: time="2025-10-30T23:48:03.468659254Z" level=info msg="StartContainer for \"670f9eef4e68796a1a46be772cf8e4b385add7c6dc990fb0b894661bf35a28c8\"" Oct 30 23:48:03.469544 containerd[1520]: time="2025-10-30T23:48:03.469497650Z" level=info msg="connecting to shim 670f9eef4e68796a1a46be772cf8e4b385add7c6dc990fb0b894661bf35a28c8" address="unix:///run/containerd/s/f0895acb4cdc0da4588a489fdd540b7cf526791927762b6900577c154e6bd2cb" protocol=ttrpc version=3 Oct 30 23:48:03.488874 systemd[1]: Started cri-containerd-670f9eef4e68796a1a46be772cf8e4b385add7c6dc990fb0b894661bf35a28c8.scope - libcontainer container 670f9eef4e68796a1a46be772cf8e4b385add7c6dc990fb0b894661bf35a28c8. Oct 30 23:48:03.514005 containerd[1520]: time="2025-10-30T23:48:03.513962555Z" level=info msg="StartContainer for \"670f9eef4e68796a1a46be772cf8e4b385add7c6dc990fb0b894661bf35a28c8\" returns successfully" Oct 30 23:48:04.274448 kubelet[1814]: E1030 23:48:04.274386 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:05.274916 kubelet[1814]: E1030 23:48:05.274867 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:06.275692 kubelet[1814]: E1030 23:48:06.275642 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:06.882366 kubelet[1814]: I1030 23:48:06.882294 1814 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-mkz44" podStartSLOduration=4.305088464 podStartE2EDuration="6.882272931s" podCreationTimestamp="2025-10-30 23:48:00 +0000 UTC" firstStartedPulling="2025-10-30 23:48:00.870116485 +0000 UTC m=+12.288785742" lastFinishedPulling="2025-10-30 23:48:03.447300952 +0000 UTC m=+14.865970209" observedRunningTime="2025-10-30 23:48:04.480032321 +0000 UTC m=+15.898701577" watchObservedRunningTime="2025-10-30 23:48:06.882272931 +0000 UTC m=+18.300942188" Oct 30 23:48:06.900464 systemd[1]: Created slice kubepods-besteffort-pod186f71c8_34b0_4d62_92e0_bd3d2aa890cb.slice - libcontainer container kubepods-besteffort-pod186f71c8_34b0_4d62_92e0_bd3d2aa890cb.slice. Oct 30 23:48:06.968548 kubelet[1814]: I1030 23:48:06.968465 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/186f71c8-34b0-4d62-92e0-bd3d2aa890cb-data\") pod \"nfs-server-provisioner-0\" (UID: \"186f71c8-34b0-4d62-92e0-bd3d2aa890cb\") " pod="default/nfs-server-provisioner-0" Oct 30 23:48:06.968690 kubelet[1814]: I1030 23:48:06.968561 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4g8tr\" (UniqueName: \"kubernetes.io/projected/186f71c8-34b0-4d62-92e0-bd3d2aa890cb-kube-api-access-4g8tr\") pod \"nfs-server-provisioner-0\" (UID: \"186f71c8-34b0-4d62-92e0-bd3d2aa890cb\") " pod="default/nfs-server-provisioner-0" Oct 30 23:48:07.204489 containerd[1520]: time="2025-10-30T23:48:07.204363580Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:186f71c8-34b0-4d62-92e0-bd3d2aa890cb,Namespace:default,Attempt:0,}" Oct 30 23:48:07.221510 kernel: cni0: port 2(vethf3fd4482) entered blocking state Oct 30 23:48:07.221617 kernel: cni0: port 2(vethf3fd4482) entered disabled state Oct 30 23:48:07.221637 kernel: vethf3fd4482: entered allmulticast mode Oct 30 23:48:07.223207 kernel: vethf3fd4482: entered promiscuous mode Oct 30 23:48:07.223966 systemd-networkd[1446]: vethf3fd4482: Link UP Oct 30 23:48:07.232526 systemd-networkd[1446]: vethf3fd4482: Gained carrier Oct 30 23:48:07.232730 kernel: cni0: port 2(vethf3fd4482) entered blocking state Oct 30 23:48:07.232797 kernel: cni0: port 2(vethf3fd4482) entered forwarding state Oct 30 23:48:07.234606 containerd[1520]: 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 30 23:48:07.234606 containerd[1520]: delegateAdd: netconf sent to delegate plugin: Oct 30 23:48:07.260054 containerd[1520]: {"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-30T23:48:07.259996899Z" level=info msg="connecting to shim 81f6512b33142b1d3a4e85a633811b0417b74a5c0b60095a418cefac1e4dd819" address="unix:///run/containerd/s/4c394c0b27356bef4b1c9c99e779f0ab087c7b87eb814a946bdeefacae2e6b90" namespace=k8s.io protocol=ttrpc version=3 Oct 30 23:48:07.275784 kubelet[1814]: E1030 23:48:07.275745 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:07.277893 systemd[1]: Started cri-containerd-81f6512b33142b1d3a4e85a633811b0417b74a5c0b60095a418cefac1e4dd819.scope - libcontainer container 81f6512b33142b1d3a4e85a633811b0417b74a5c0b60095a418cefac1e4dd819. Oct 30 23:48:07.289096 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 30 23:48:07.309848 containerd[1520]: time="2025-10-30T23:48:07.309104273Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:186f71c8-34b0-4d62-92e0-bd3d2aa890cb,Namespace:default,Attempt:0,} returns sandbox id \"81f6512b33142b1d3a4e85a633811b0417b74a5c0b60095a418cefac1e4dd819\"" Oct 30 23:48:07.311215 containerd[1520]: time="2025-10-30T23:48:07.311181246Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 30 23:48:08.276498 kubelet[1814]: E1030 23:48:08.276443 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:08.840817 systemd-networkd[1446]: vethf3fd4482: Gained IPv6LL Oct 30 23:48:09.098461 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2866687623.mount: Deactivated successfully. Oct 30 23:48:09.263277 kubelet[1814]: E1030 23:48:09.263222 1814 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:09.277579 kubelet[1814]: E1030 23:48:09.277540 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:10.277925 kubelet[1814]: E1030 23:48:10.277886 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:10.536630 containerd[1520]: time="2025-10-30T23:48:10.536482271Z" 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 30 23:48:10.537945 containerd[1520]: time="2025-10-30T23:48:10.537900782Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Oct 30 23:48:10.539621 containerd[1520]: time="2025-10-30T23:48:10.539092864Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:48:10.541992 containerd[1520]: time="2025-10-30T23:48:10.541955781Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:48:10.543102 containerd[1520]: time="2025-10-30T23:48:10.543068178Z" 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.231837872s" Oct 30 23:48:10.543173 containerd[1520]: time="2025-10-30T23:48:10.543108780Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 30 23:48:10.547537 containerd[1520]: time="2025-10-30T23:48:10.547490752Z" level=info msg="CreateContainer within sandbox \"81f6512b33142b1d3a4e85a633811b0417b74a5c0b60095a418cefac1e4dd819\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 30 23:48:10.554588 containerd[1520]: time="2025-10-30T23:48:10.554524878Z" level=info msg="Container 2a9b2234c77e5492891d87dcf335c890089a7999747eb3d725de728c22f44301: CDI devices from CRI Config.CDIDevices: []" Oct 30 23:48:10.563151 containerd[1520]: time="2025-10-30T23:48:10.563096643Z" level=info msg="CreateContainer within sandbox \"81f6512b33142b1d3a4e85a633811b0417b74a5c0b60095a418cefac1e4dd819\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"2a9b2234c77e5492891d87dcf335c890089a7999747eb3d725de728c22f44301\"" Oct 30 23:48:10.563712 containerd[1520]: time="2025-10-30T23:48:10.563647367Z" level=info msg="StartContainer for \"2a9b2234c77e5492891d87dcf335c890089a7999747eb3d725de728c22f44301\"" Oct 30 23:48:10.564815 containerd[1520]: time="2025-10-30T23:48:10.564788457Z" level=info msg="connecting to shim 2a9b2234c77e5492891d87dcf335c890089a7999747eb3d725de728c22f44301" address="unix:///run/containerd/s/4c394c0b27356bef4b1c9c99e779f0ab087c7b87eb814a946bdeefacae2e6b90" protocol=ttrpc version=3 Oct 30 23:48:10.594940 systemd[1]: Started cri-containerd-2a9b2234c77e5492891d87dcf335c890089a7999747eb3d725de728c22f44301.scope - libcontainer container 2a9b2234c77e5492891d87dcf335c890089a7999747eb3d725de728c22f44301. Oct 30 23:48:10.621159 containerd[1520]: time="2025-10-30T23:48:10.621121651Z" level=info msg="StartContainer for \"2a9b2234c77e5492891d87dcf335c890089a7999747eb3d725de728c22f44301\" returns successfully" Oct 30 23:48:11.279494 kubelet[1814]: E1030 23:48:11.279443 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:11.503431 kubelet[1814]: I1030 23:48:11.503362 1814 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.270428638 podStartE2EDuration="5.503346277s" podCreationTimestamp="2025-10-30 23:48:06 +0000 UTC" firstStartedPulling="2025-10-30 23:48:07.31090695 +0000 UTC m=+18.729576207" lastFinishedPulling="2025-10-30 23:48:10.543824589 +0000 UTC m=+21.962493846" observedRunningTime="2025-10-30 23:48:11.502743412 +0000 UTC m=+22.921412669" watchObservedRunningTime="2025-10-30 23:48:11.503346277 +0000 UTC m=+22.922015534" Oct 30 23:48:12.280570 kubelet[1814]: E1030 23:48:12.280519 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:13.281299 kubelet[1814]: E1030 23:48:13.281245 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:14.282164 kubelet[1814]: E1030 23:48:14.282113 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:15.283299 kubelet[1814]: E1030 23:48:15.283246 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:15.897940 systemd[1]: Created slice kubepods-besteffort-pod4a5aab1a_3a26_42fd_bc91_60becc8b015b.slice - libcontainer container kubepods-besteffort-pod4a5aab1a_3a26_42fd_bc91_60becc8b015b.slice. Oct 30 23:48:16.021006 kubelet[1814]: I1030 23:48:16.020961 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-d9lwx\" (UniqueName: \"kubernetes.io/projected/4a5aab1a-3a26-42fd-bc91-60becc8b015b-kube-api-access-d9lwx\") pod \"test-pod-1\" (UID: \"4a5aab1a-3a26-42fd-bc91-60becc8b015b\") " pod="default/test-pod-1" Oct 30 23:48:16.021133 kubelet[1814]: I1030 23:48:16.021047 1814 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-df8f3181-0123-4039-80f3-97df0b5ae9c3\" (UniqueName: \"kubernetes.io/nfs/4a5aab1a-3a26-42fd-bc91-60becc8b015b-pvc-df8f3181-0123-4039-80f3-97df0b5ae9c3\") pod \"test-pod-1\" (UID: \"4a5aab1a-3a26-42fd-bc91-60becc8b015b\") " pod="default/test-pod-1" Oct 30 23:48:16.144736 kernel: netfs: FS-Cache loaded Oct 30 23:48:16.170329 kernel: RPC: Registered named UNIX socket transport module. Oct 30 23:48:16.170464 kernel: RPC: Registered udp transport module. Oct 30 23:48:16.170484 kernel: RPC: Registered tcp transport module. Oct 30 23:48:16.170500 kernel: RPC: Registered tcp-with-tls transport module. Oct 30 23:48:16.172054 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 30 23:48:16.283514 kubelet[1814]: E1030 23:48:16.283465 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:16.347824 kernel: NFS: Registering the id_resolver key type Oct 30 23:48:16.348080 kernel: Key type id_resolver registered Oct 30 23:48:16.348097 kernel: Key type id_legacy registered Oct 30 23:48:16.367639 nfsidmap[2791]: 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 30 23:48:16.368395 nfsidmap[2791]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 30 23:48:16.370181 nfsidmap[2792]: 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 30 23:48:16.370350 nfsidmap[2792]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 30 23:48:16.379391 nfsrahead[2794]: setting /var/lib/kubelet/pods/4a5aab1a-3a26-42fd-bc91-60becc8b015b/volumes/kubernetes.io~nfs/pvc-df8f3181-0123-4039-80f3-97df0b5ae9c3 readahead to 128 Oct 30 23:48:16.502120 containerd[1520]: time="2025-10-30T23:48:16.501509538Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4a5aab1a-3a26-42fd-bc91-60becc8b015b,Namespace:default,Attempt:0,}" Oct 30 23:48:16.516980 systemd-networkd[1446]: veth789cf8ac: Link UP Oct 30 23:48:16.520993 kernel: cni0: port 3(veth789cf8ac) entered blocking state Oct 30 23:48:16.521091 kernel: cni0: port 3(veth789cf8ac) entered disabled state Oct 30 23:48:16.521109 kernel: veth789cf8ac: entered allmulticast mode Oct 30 23:48:16.521124 kernel: veth789cf8ac: entered promiscuous mode Oct 30 23:48:16.536141 kernel: cni0: port 3(veth789cf8ac) entered blocking state Oct 30 23:48:16.536219 kernel: cni0: port 3(veth789cf8ac) entered forwarding state Oct 30 23:48:16.537637 systemd-networkd[1446]: veth789cf8ac: Gained carrier Oct 30 23:48:16.542245 containerd[1520]: 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 30 23:48:16.542245 containerd[1520]: delegateAdd: netconf sent to delegate plugin: Oct 30 23:48:16.575343 containerd[1520]: {"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-30T23:48:16.575295184Z" level=info msg="connecting to shim 70bc10ca40f69a5343259bd6362e9728cfccf1da19a2dacd61ea1f90f642d8c3" address="unix:///run/containerd/s/21680b6c0c0f1b15740f467bcefdcc2b7890bdee9c66cc8849d618ad19c75190" namespace=k8s.io protocol=ttrpc version=3 Oct 30 23:48:16.608964 systemd[1]: Started cri-containerd-70bc10ca40f69a5343259bd6362e9728cfccf1da19a2dacd61ea1f90f642d8c3.scope - libcontainer container 70bc10ca40f69a5343259bd6362e9728cfccf1da19a2dacd61ea1f90f642d8c3. Oct 30 23:48:16.621289 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 30 23:48:16.649833 containerd[1520]: time="2025-10-30T23:48:16.649789478Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:4a5aab1a-3a26-42fd-bc91-60becc8b015b,Namespace:default,Attempt:0,} returns sandbox id \"70bc10ca40f69a5343259bd6362e9728cfccf1da19a2dacd61ea1f90f642d8c3\"" Oct 30 23:48:16.651919 containerd[1520]: time="2025-10-30T23:48:16.651788793Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 30 23:48:16.916004 containerd[1520]: time="2025-10-30T23:48:16.915216043Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 30 23:48:16.917322 containerd[1520]: time="2025-10-30T23:48:16.917277373Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 30 23:48:16.920081 containerd[1520]: time="2025-10-30T23:48:16.920019984Z" 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 268.196543ms" Oct 30 23:48:16.920081 containerd[1520]: time="2025-10-30T23:48:16.920067756Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 30 23:48:16.924567 containerd[1520]: time="2025-10-30T23:48:16.924526815Z" level=info msg="CreateContainer within sandbox \"70bc10ca40f69a5343259bd6362e9728cfccf1da19a2dacd61ea1f90f642d8c3\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 30 23:48:16.933884 containerd[1520]: time="2025-10-30T23:48:16.933837106Z" level=info msg="Container 0b6687dcb28b066f2b00c6b5179484336319be10e0ce263e2390dfff67529924: CDI devices from CRI Config.CDIDevices: []" Oct 30 23:48:16.940727 containerd[1520]: time="2025-10-30T23:48:16.940660687Z" level=info msg="CreateContainer within sandbox \"70bc10ca40f69a5343259bd6362e9728cfccf1da19a2dacd61ea1f90f642d8c3\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"0b6687dcb28b066f2b00c6b5179484336319be10e0ce263e2390dfff67529924\"" Oct 30 23:48:16.942375 containerd[1520]: time="2025-10-30T23:48:16.941261950Z" level=info msg="StartContainer for \"0b6687dcb28b066f2b00c6b5179484336319be10e0ce263e2390dfff67529924\"" Oct 30 23:48:16.942375 containerd[1520]: time="2025-10-30T23:48:16.942257266Z" level=info msg="connecting to shim 0b6687dcb28b066f2b00c6b5179484336319be10e0ce263e2390dfff67529924" address="unix:///run/containerd/s/21680b6c0c0f1b15740f467bcefdcc2b7890bdee9c66cc8849d618ad19c75190" protocol=ttrpc version=3 Oct 30 23:48:16.964902 systemd[1]: Started cri-containerd-0b6687dcb28b066f2b00c6b5179484336319be10e0ce263e2390dfff67529924.scope - libcontainer container 0b6687dcb28b066f2b00c6b5179484336319be10e0ce263e2390dfff67529924. Oct 30 23:48:16.992991 containerd[1520]: time="2025-10-30T23:48:16.992949587Z" level=info msg="StartContainer for \"0b6687dcb28b066f2b00c6b5179484336319be10e0ce263e2390dfff67529924\" returns successfully" Oct 30 23:48:17.284495 kubelet[1814]: E1030 23:48:17.284343 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:17.507973 kubelet[1814]: I1030 23:48:17.507903 1814 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=10.237741514 podStartE2EDuration="10.50788644s" podCreationTimestamp="2025-10-30 23:48:07 +0000 UTC" firstStartedPulling="2025-10-30 23:48:16.650694533 +0000 UTC m=+28.069363790" lastFinishedPulling="2025-10-30 23:48:16.920839459 +0000 UTC m=+28.339508716" observedRunningTime="2025-10-30 23:48:17.507759812 +0000 UTC m=+28.926429069" watchObservedRunningTime="2025-10-30 23:48:17.50788644 +0000 UTC m=+28.926555697" Oct 30 23:48:18.285409 kubelet[1814]: E1030 23:48:18.285359 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 30 23:48:18.312941 systemd-networkd[1446]: veth789cf8ac: Gained IPv6LL Oct 30 23:48:19.286288 kubelet[1814]: E1030 23:48:19.286229 1814 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"