Nov 23 22:56:23.786199 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 23 22:56:23.786222 kernel: Linux version 6.12.58-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 Sun Nov 23 20:49:09 -00 2025 Nov 23 22:56:23.786232 kernel: KASLR enabled Nov 23 22:56:23.786237 kernel: efi: EFI v2.7 by EDK II Nov 23 22:56:23.786243 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Nov 23 22:56:23.786248 kernel: random: crng init done Nov 23 22:56:23.786255 kernel: secureboot: Secure boot disabled Nov 23 22:56:23.786261 kernel: ACPI: Early table checksum verification disabled Nov 23 22:56:23.786267 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 23 22:56:23.786274 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 23 22:56:23.786280 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786289 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786295 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786301 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786308 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786316 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786323 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786329 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786335 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 23 22:56:23.786341 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 23 22:56:23.786347 kernel: ACPI: Use ACPI SPCR as default console: No Nov 23 22:56:23.786353 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 23 22:56:23.786359 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Nov 23 22:56:23.786365 kernel: Zone ranges: Nov 23 22:56:23.786371 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 23 22:56:23.786378 kernel: DMA32 empty Nov 23 22:56:23.786384 kernel: Normal empty Nov 23 22:56:23.786390 kernel: Device empty Nov 23 22:56:23.786399 kernel: Movable zone start for each node Nov 23 22:56:23.786406 kernel: Early memory node ranges Nov 23 22:56:23.786412 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Nov 23 22:56:23.786418 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Nov 23 22:56:23.786424 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Nov 23 22:56:23.786430 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 23 22:56:23.786436 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 23 22:56:23.786442 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 23 22:56:23.786448 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 23 22:56:23.786456 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 23 22:56:23.786462 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 23 22:56:23.786468 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 23 22:56:23.786476 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 23 22:56:23.786482 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 23 22:56:23.786489 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 23 22:56:23.786496 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 23 22:56:23.786503 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Nov 23 22:56:23.786509 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Nov 23 22:56:23.786516 kernel: psci: probing for conduit method from ACPI. Nov 23 22:56:23.786522 kernel: psci: PSCIv1.1 detected in firmware. Nov 23 22:56:23.786529 kernel: psci: Using standard PSCI v0.2 function IDs Nov 23 22:56:23.786536 kernel: psci: Trusted OS migration not required Nov 23 22:56:23.786543 kernel: psci: SMC Calling Convention v1.1 Nov 23 22:56:23.786549 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Nov 23 22:56:23.786556 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Nov 23 22:56:23.786564 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Nov 23 22:56:23.786571 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 23 22:56:23.786578 kernel: Detected PIPT I-cache on CPU0 Nov 23 22:56:23.786585 kernel: CPU features: detected: GIC system register CPU interface Nov 23 22:56:23.786591 kernel: CPU features: detected: Spectre-v4 Nov 23 22:56:23.786598 kernel: CPU features: detected: Spectre-BHB Nov 23 22:56:23.786604 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 23 22:56:23.786611 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 23 22:56:23.786617 kernel: CPU features: detected: ARM erratum 1418040 Nov 23 22:56:23.786623 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 23 22:56:23.786630 kernel: alternatives: applying boot alternatives Nov 23 22:56:23.786637 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=c01798725f53da1d62d166036caa3c72754cb158fe469d9d9e3df0d6cadc7a34 Nov 23 22:56:23.786645 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 23 22:56:23.786652 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 23 22:56:23.786669 kernel: Fallback order for Node 0: 0 Nov 23 22:56:23.786676 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Nov 23 22:56:23.786683 kernel: Policy zone: DMA Nov 23 22:56:23.786689 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 23 22:56:23.786695 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Nov 23 22:56:23.786702 kernel: software IO TLB: area num 4. Nov 23 22:56:23.786708 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Nov 23 22:56:23.786714 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Nov 23 22:56:23.786721 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 23 22:56:23.786730 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 23 22:56:23.786737 kernel: rcu: RCU event tracing is enabled. Nov 23 22:56:23.786743 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 23 22:56:23.786750 kernel: Trampoline variant of Tasks RCU enabled. Nov 23 22:56:23.786757 kernel: Tracing variant of Tasks RCU enabled. Nov 23 22:56:23.786764 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 23 22:56:23.786770 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 23 22:56:23.786780 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 23 22:56:23.786786 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 23 22:56:23.786793 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 23 22:56:23.786802 kernel: GICv3: 256 SPIs implemented Nov 23 22:56:23.786816 kernel: GICv3: 0 Extended SPIs implemented Nov 23 22:56:23.786824 kernel: Root IRQ handler: gic_handle_irq Nov 23 22:56:23.786831 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Nov 23 22:56:23.786837 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Nov 23 22:56:23.786844 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 23 22:56:23.786850 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 23 22:56:23.786857 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Nov 23 22:56:23.786863 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Nov 23 22:56:23.786870 kernel: GICv3: using LPI property table @0x0000000040130000 Nov 23 22:56:23.786877 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Nov 23 22:56:23.786883 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 23 22:56:23.786890 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:56:23.786898 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 23 22:56:23.786905 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 23 22:56:23.786912 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 23 22:56:23.786918 kernel: arm-pv: using stolen time PV Nov 23 22:56:23.786925 kernel: Console: colour dummy device 80x25 Nov 23 22:56:23.786932 kernel: ACPI: Core revision 20240827 Nov 23 22:56:23.786939 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 23 22:56:23.786946 kernel: pid_max: default: 32768 minimum: 301 Nov 23 22:56:23.786953 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 23 22:56:23.786959 kernel: landlock: Up and running. Nov 23 22:56:23.786968 kernel: SELinux: Initializing. Nov 23 22:56:23.786974 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 23 22:56:23.786981 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 23 22:56:23.786988 kernel: rcu: Hierarchical SRCU implementation. Nov 23 22:56:23.786995 kernel: rcu: Max phase no-delay instances is 400. Nov 23 22:56:23.787002 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 23 22:56:23.787009 kernel: Remapping and enabling EFI services. Nov 23 22:56:23.787015 kernel: smp: Bringing up secondary CPUs ... Nov 23 22:56:23.787022 kernel: Detected PIPT I-cache on CPU1 Nov 23 22:56:23.787035 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 23 22:56:23.787042 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Nov 23 22:56:23.787049 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:56:23.787057 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 23 22:56:23.787064 kernel: Detected PIPT I-cache on CPU2 Nov 23 22:56:23.787071 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 23 22:56:23.787078 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Nov 23 22:56:23.787086 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:56:23.787094 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 23 22:56:23.787101 kernel: Detected PIPT I-cache on CPU3 Nov 23 22:56:23.787108 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 23 22:56:23.787115 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Nov 23 22:56:23.787122 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 23 22:56:23.787129 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 23 22:56:23.787136 kernel: smp: Brought up 1 node, 4 CPUs Nov 23 22:56:23.787151 kernel: SMP: Total of 4 processors activated. Nov 23 22:56:23.787158 kernel: CPU: All CPU(s) started at EL1 Nov 23 22:56:23.787167 kernel: CPU features: detected: 32-bit EL0 Support Nov 23 22:56:23.787174 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 23 22:56:23.787181 kernel: CPU features: detected: Common not Private translations Nov 23 22:56:23.787188 kernel: CPU features: detected: CRC32 instructions Nov 23 22:56:23.787195 kernel: CPU features: detected: Enhanced Virtualization Traps Nov 23 22:56:23.787202 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 23 22:56:23.787209 kernel: CPU features: detected: LSE atomic instructions Nov 23 22:56:23.787215 kernel: CPU features: detected: Privileged Access Never Nov 23 22:56:23.787222 kernel: CPU features: detected: RAS Extension Support Nov 23 22:56:23.787230 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 23 22:56:23.787237 kernel: alternatives: applying system-wide alternatives Nov 23 22:56:23.787244 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Nov 23 22:56:23.787252 kernel: Memory: 2423776K/2572288K available (11200K kernel code, 2456K rwdata, 9084K rodata, 39552K init, 1038K bss, 126176K reserved, 16384K cma-reserved) Nov 23 22:56:23.787259 kernel: devtmpfs: initialized Nov 23 22:56:23.787266 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 23 22:56:23.787273 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 23 22:56:23.787280 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 23 22:56:23.787287 kernel: 0 pages in range for non-PLT usage Nov 23 22:56:23.787296 kernel: 508400 pages in range for PLT usage Nov 23 22:56:23.787303 kernel: pinctrl core: initialized pinctrl subsystem Nov 23 22:56:23.787310 kernel: SMBIOS 3.0.0 present. Nov 23 22:56:23.787318 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 23 22:56:23.787325 kernel: DMI: Memory slots populated: 1/1 Nov 23 22:56:23.787332 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 23 22:56:23.787339 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 23 22:56:23.787346 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 23 22:56:23.787354 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 23 22:56:23.787363 kernel: audit: initializing netlink subsys (disabled) Nov 23 22:56:23.787370 kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Nov 23 22:56:23.787377 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 23 22:56:23.787385 kernel: cpuidle: using governor menu Nov 23 22:56:23.787392 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 23 22:56:23.787399 kernel: ASID allocator initialised with 32768 entries Nov 23 22:56:23.787406 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 23 22:56:23.787413 kernel: Serial: AMBA PL011 UART driver Nov 23 22:56:23.787421 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 23 22:56:23.787429 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 23 22:56:23.787436 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 23 22:56:23.787443 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 23 22:56:23.787450 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 23 22:56:23.787457 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 23 22:56:23.787464 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 23 22:56:23.787471 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 23 22:56:23.787477 kernel: ACPI: Added _OSI(Module Device) Nov 23 22:56:23.787484 kernel: ACPI: Added _OSI(Processor Device) Nov 23 22:56:23.787493 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 23 22:56:23.787500 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 23 22:56:23.787506 kernel: ACPI: Interpreter enabled Nov 23 22:56:23.787513 kernel: ACPI: Using GIC for interrupt routing Nov 23 22:56:23.787520 kernel: ACPI: MCFG table detected, 1 entries Nov 23 22:56:23.787527 kernel: ACPI: CPU0 has been hot-added Nov 23 22:56:23.787534 kernel: ACPI: CPU1 has been hot-added Nov 23 22:56:23.787541 kernel: ACPI: CPU2 has been hot-added Nov 23 22:56:23.787548 kernel: ACPI: CPU3 has been hot-added Nov 23 22:56:23.787555 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 23 22:56:23.787563 kernel: printk: legacy console [ttyAMA0] enabled Nov 23 22:56:23.787573 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 23 22:56:23.787754 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 23 22:56:23.787830 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 23 22:56:23.787891 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 23 22:56:23.787950 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 23 22:56:23.788009 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 23 22:56:23.788021 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 23 22:56:23.788028 kernel: PCI host bridge to bus 0000:00 Nov 23 22:56:23.788098 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 23 22:56:23.788167 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 23 22:56:23.788223 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 23 22:56:23.788277 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 23 22:56:23.788358 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Nov 23 22:56:23.788440 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Nov 23 22:56:23.788517 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Nov 23 22:56:23.788581 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Nov 23 22:56:23.788642 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Nov 23 22:56:23.788727 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Nov 23 22:56:23.788789 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Nov 23 22:56:23.788855 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Nov 23 22:56:23.788910 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 23 22:56:23.788962 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 23 22:56:23.789016 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 23 22:56:23.789025 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 23 22:56:23.789032 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 23 22:56:23.789039 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 23 22:56:23.789046 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 23 22:56:23.789055 kernel: iommu: Default domain type: Translated Nov 23 22:56:23.789062 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 23 22:56:23.789069 kernel: efivars: Registered efivars operations Nov 23 22:56:23.789076 kernel: vgaarb: loaded Nov 23 22:56:23.789083 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 23 22:56:23.789090 kernel: VFS: Disk quotas dquot_6.6.0 Nov 23 22:56:23.789097 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 23 22:56:23.789104 kernel: pnp: PnP ACPI init Nov 23 22:56:23.789181 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 23 22:56:23.789194 kernel: pnp: PnP ACPI: found 1 devices Nov 23 22:56:23.789201 kernel: NET: Registered PF_INET protocol family Nov 23 22:56:23.789208 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 23 22:56:23.789215 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 23 22:56:23.789223 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 23 22:56:23.789230 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 23 22:56:23.789237 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 23 22:56:23.789244 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 23 22:56:23.789252 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 23 22:56:23.789260 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 23 22:56:23.789267 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 23 22:56:23.789274 kernel: PCI: CLS 0 bytes, default 64 Nov 23 22:56:23.789281 kernel: kvm [1]: HYP mode not available Nov 23 22:56:23.789288 kernel: Initialise system trusted keyrings Nov 23 22:56:23.789295 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 23 22:56:23.789302 kernel: Key type asymmetric registered Nov 23 22:56:23.789309 kernel: Asymmetric key parser 'x509' registered Nov 23 22:56:23.789317 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Nov 23 22:56:23.789324 kernel: io scheduler mq-deadline registered Nov 23 22:56:23.789331 kernel: io scheduler kyber registered Nov 23 22:56:23.789338 kernel: io scheduler bfq registered Nov 23 22:56:23.789345 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 23 22:56:23.789352 kernel: ACPI: button: Power Button [PWRB] Nov 23 22:56:23.789360 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 23 22:56:23.789422 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 23 22:56:23.789432 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 23 22:56:23.789440 kernel: thunder_xcv, ver 1.0 Nov 23 22:56:23.789447 kernel: thunder_bgx, ver 1.0 Nov 23 22:56:23.789454 kernel: nicpf, ver 1.0 Nov 23 22:56:23.789461 kernel: nicvf, ver 1.0 Nov 23 22:56:23.789531 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 23 22:56:23.789588 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-23T22:56:23 UTC (1763938583) Nov 23 22:56:23.789598 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 23 22:56:23.789605 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Nov 23 22:56:23.789614 kernel: watchdog: NMI not fully supported Nov 23 22:56:23.789621 kernel: watchdog: Hard watchdog permanently disabled Nov 23 22:56:23.789628 kernel: NET: Registered PF_INET6 protocol family Nov 23 22:56:23.789635 kernel: Segment Routing with IPv6 Nov 23 22:56:23.789642 kernel: In-situ OAM (IOAM) with IPv6 Nov 23 22:56:23.789649 kernel: NET: Registered PF_PACKET protocol family Nov 23 22:56:23.789666 kernel: Key type dns_resolver registered Nov 23 22:56:23.789693 kernel: registered taskstats version 1 Nov 23 22:56:23.789702 kernel: Loading compiled-in X.509 certificates Nov 23 22:56:23.789710 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.58-flatcar: 98b0841f2908e51633cd38699ad12796cadb7bd1' Nov 23 22:56:23.789721 kernel: Demotion targets for Node 0: null Nov 23 22:56:23.789728 kernel: Key type .fscrypt registered Nov 23 22:56:23.789735 kernel: Key type fscrypt-provisioning registered Nov 23 22:56:23.789742 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 23 22:56:23.789750 kernel: ima: Allocated hash algorithm: sha1 Nov 23 22:56:23.789757 kernel: ima: No architecture policies found Nov 23 22:56:23.789764 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 23 22:56:23.789771 kernel: clk: Disabling unused clocks Nov 23 22:56:23.789778 kernel: PM: genpd: Disabling unused power domains Nov 23 22:56:23.789787 kernel: Warning: unable to open an initial console. Nov 23 22:56:23.789795 kernel: Freeing unused kernel memory: 39552K Nov 23 22:56:23.789802 kernel: Run /init as init process Nov 23 22:56:23.789809 kernel: with arguments: Nov 23 22:56:23.789816 kernel: /init Nov 23 22:56:23.789823 kernel: with environment: Nov 23 22:56:23.789830 kernel: HOME=/ Nov 23 22:56:23.789837 kernel: TERM=linux Nov 23 22:56:23.789845 systemd[1]: Successfully made /usr/ read-only. Nov 23 22:56:23.789857 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) Nov 23 22:56:23.789865 systemd[1]: Detected virtualization kvm. Nov 23 22:56:23.789873 systemd[1]: Detected architecture arm64. Nov 23 22:56:23.789880 systemd[1]: Running in initrd. Nov 23 22:56:23.789887 systemd[1]: No hostname configured, using default hostname. Nov 23 22:56:23.789895 systemd[1]: Hostname set to . Nov 23 22:56:23.789902 systemd[1]: Initializing machine ID from VM UUID. Nov 23 22:56:23.789911 systemd[1]: Queued start job for default target initrd.target. Nov 23 22:56:23.789919 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 23 22:56:23.789927 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 23 22:56:23.789935 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 23 22:56:23.789943 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 23 22:56:23.789951 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 23 22:56:23.789959 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 23 22:56:23.789968 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 23 22:56:23.789976 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 23 22:56:23.789983 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 23 22:56:23.789991 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 23 22:56:23.789999 systemd[1]: Reached target paths.target - Path Units. Nov 23 22:56:23.790006 systemd[1]: Reached target slices.target - Slice Units. Nov 23 22:56:23.790013 systemd[1]: Reached target swap.target - Swaps. Nov 23 22:56:23.790021 systemd[1]: Reached target timers.target - Timer Units. Nov 23 22:56:23.790030 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 23 22:56:23.790038 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 23 22:56:23.790045 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 23 22:56:23.790053 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 23 22:56:23.790061 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 23 22:56:23.790068 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 23 22:56:23.790076 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 23 22:56:23.790083 systemd[1]: Reached target sockets.target - Socket Units. Nov 23 22:56:23.790092 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 23 22:56:23.790101 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 23 22:56:23.790108 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 23 22:56:23.790116 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). Nov 23 22:56:23.790124 systemd[1]: Starting systemd-fsck-usr.service... Nov 23 22:56:23.790132 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 23 22:56:23.790148 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 23 22:56:23.790156 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:56:23.790164 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 23 22:56:23.790175 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 23 22:56:23.790183 systemd[1]: Finished systemd-fsck-usr.service. Nov 23 22:56:23.790191 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 23 22:56:23.790220 systemd-journald[246]: Collecting audit messages is disabled. Nov 23 22:56:23.790242 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:56:23.790252 systemd-journald[246]: Journal started Nov 23 22:56:23.790272 systemd-journald[246]: Runtime Journal (/run/log/journal/56d1aea11e0b4a3486a0bb44852a6e7e) is 6M, max 48.5M, 42.4M free. Nov 23 22:56:23.796743 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 23 22:56:23.784104 systemd-modules-load[247]: Inserted module 'overlay' Nov 23 22:56:23.800529 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 23 22:56:23.804685 kernel: Bridge firewalling registered Nov 23 22:56:23.804735 systemd[1]: Started systemd-journald.service - Journal Service. Nov 23 22:56:23.802257 systemd-modules-load[247]: Inserted module 'br_netfilter' Nov 23 22:56:23.804583 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 23 22:56:23.808157 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 23 22:56:23.810809 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 23 22:56:23.819288 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 23 22:56:23.821866 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 23 22:56:23.826707 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 23 22:56:23.830834 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 23 22:56:23.832390 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 23 22:56:23.834341 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 23 22:56:23.835409 systemd-tmpfiles[270]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 23 22:56:23.840737 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 23 22:56:23.843649 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 23 22:56:23.848875 dracut-cmdline[285]: 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=c01798725f53da1d62d166036caa3c72754cb158fe469d9d9e3df0d6cadc7a34 Nov 23 22:56:23.889243 systemd-resolved[297]: Positive Trust Anchors: Nov 23 22:56:23.889262 systemd-resolved[297]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 23 22:56:23.889294 systemd-resolved[297]: 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 Nov 23 22:56:23.894499 systemd-resolved[297]: Defaulting to hostname 'linux'. Nov 23 22:56:23.895533 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 23 22:56:23.899409 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 23 22:56:23.935697 kernel: SCSI subsystem initialized Nov 23 22:56:23.940691 kernel: Loading iSCSI transport class v2.0-870. Nov 23 22:56:23.948700 kernel: iscsi: registered transport (tcp) Nov 23 22:56:23.964726 kernel: iscsi: registered transport (qla4xxx) Nov 23 22:56:23.964789 kernel: QLogic iSCSI HBA Driver Nov 23 22:56:23.982395 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 23 22:56:24.006976 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 23 22:56:24.010564 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 23 22:56:24.060524 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 23 22:56:24.063149 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 23 22:56:24.133722 kernel: raid6: neonx8 gen() 15763 MB/s Nov 23 22:56:24.150708 kernel: raid6: neonx4 gen() 15695 MB/s Nov 23 22:56:24.167696 kernel: raid6: neonx2 gen() 13179 MB/s Nov 23 22:56:24.184748 kernel: raid6: neonx1 gen() 10439 MB/s Nov 23 22:56:24.201702 kernel: raid6: int64x8 gen() 6884 MB/s Nov 23 22:56:24.218703 kernel: raid6: int64x4 gen() 7308 MB/s Nov 23 22:56:24.235700 kernel: raid6: int64x2 gen() 6093 MB/s Nov 23 22:56:24.252823 kernel: raid6: int64x1 gen() 5024 MB/s Nov 23 22:56:24.252881 kernel: raid6: using algorithm neonx8 gen() 15763 MB/s Nov 23 22:56:24.270820 kernel: raid6: .... xor() 11404 MB/s, rmw enabled Nov 23 22:56:24.270868 kernel: raid6: using neon recovery algorithm Nov 23 22:56:24.279146 kernel: xor: measuring software checksum speed Nov 23 22:56:24.279202 kernel: 8regs : 21533 MB/sec Nov 23 22:56:24.279211 kernel: 32regs : 21653 MB/sec Nov 23 22:56:24.279755 kernel: arm64_neon : 26656 MB/sec Nov 23 22:56:24.279805 kernel: xor: using function: arm64_neon (26656 MB/sec) Nov 23 22:56:24.332706 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 23 22:56:24.340338 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 23 22:56:24.343436 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 23 22:56:24.382625 systemd-udevd[498]: Using default interface naming scheme 'v255'. Nov 23 22:56:24.387730 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 23 22:56:24.390051 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 23 22:56:24.417094 dracut-pre-trigger[505]: rd.md=0: removing MD RAID activation Nov 23 22:56:24.444739 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 23 22:56:24.447293 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 23 22:56:24.502841 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 23 22:56:24.506558 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 23 22:56:24.564693 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Nov 23 22:56:24.567744 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Nov 23 22:56:24.576424 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 23 22:56:24.576484 kernel: GPT:9289727 != 19775487 Nov 23 22:56:24.577740 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 23 22:56:24.577798 kernel: GPT:9289727 != 19775487 Nov 23 22:56:24.580912 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 23 22:56:24.580966 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 23 22:56:24.581509 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 23 22:56:24.583359 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:56:24.585950 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:56:24.587922 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:56:24.619260 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 23 22:56:24.620708 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:56:24.626722 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 23 22:56:24.640043 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 23 22:56:24.652337 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 23 22:56:24.658571 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 23 22:56:24.659803 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 23 22:56:24.662920 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 23 22:56:24.665093 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 23 22:56:24.667056 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 23 22:56:24.671045 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 23 22:56:24.673062 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 23 22:56:24.702279 disk-uuid[586]: Primary Header is updated. Nov 23 22:56:24.702279 disk-uuid[586]: Secondary Entries is updated. Nov 23 22:56:24.702279 disk-uuid[586]: Secondary Header is updated. Nov 23 22:56:24.707735 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 23 22:56:24.709945 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 23 22:56:25.717688 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 23 22:56:25.719278 disk-uuid[591]: The operation has completed successfully. Nov 23 22:56:25.760466 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 23 22:56:25.760596 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 23 22:56:25.779193 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 23 22:56:25.805988 sh[606]: Success Nov 23 22:56:25.821515 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 23 22:56:25.821572 kernel: device-mapper: uevent: version 1.0.3 Nov 23 22:56:25.821583 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 23 22:56:25.832768 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Nov 23 22:56:25.862624 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 23 22:56:25.865740 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 23 22:56:25.894633 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 23 22:56:25.902914 kernel: BTRFS: device fsid 9fed50bd-c943-4402-9e9a-f39625143eb9 devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (619) Nov 23 22:56:25.905087 kernel: BTRFS info (device dm-0): first mount of filesystem 9fed50bd-c943-4402-9e9a-f39625143eb9 Nov 23 22:56:25.905150 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:56:25.910134 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 23 22:56:25.910184 kernel: BTRFS info (device dm-0): enabling free space tree Nov 23 22:56:25.913227 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 23 22:56:25.914815 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 23 22:56:25.916345 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 23 22:56:25.918339 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 23 22:56:25.920756 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 23 22:56:25.946185 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (649) Nov 23 22:56:25.946236 kernel: BTRFS info (device vda6): first mount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:56:25.946245 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:56:25.951148 kernel: BTRFS info (device vda6): turning on async discard Nov 23 22:56:25.951217 kernel: BTRFS info (device vda6): enabling free space tree Nov 23 22:56:25.956738 kernel: BTRFS info (device vda6): last unmount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:56:25.957744 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 23 22:56:25.960078 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 23 22:56:26.047862 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 23 22:56:26.051241 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 23 22:56:26.069166 ignition[691]: Ignition 2.22.0 Nov 23 22:56:26.069182 ignition[691]: Stage: fetch-offline Nov 23 22:56:26.069216 ignition[691]: no configs at "/usr/lib/ignition/base.d" Nov 23 22:56:26.069224 ignition[691]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:56:26.069309 ignition[691]: parsed url from cmdline: "" Nov 23 22:56:26.069312 ignition[691]: no config URL provided Nov 23 22:56:26.069317 ignition[691]: reading system config file "/usr/lib/ignition/user.ign" Nov 23 22:56:26.069324 ignition[691]: no config at "/usr/lib/ignition/user.ign" Nov 23 22:56:26.069345 ignition[691]: op(1): [started] loading QEMU firmware config module Nov 23 22:56:26.069350 ignition[691]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 23 22:56:26.076605 ignition[691]: op(1): [finished] loading QEMU firmware config module Nov 23 22:56:26.083975 ignition[691]: parsing config with SHA512: 4f5df3a5e6c9b7ca86a0635ed83ada9f044f2403eec963d804d562909a017157550660702c3f850c20d3b5df8a058c62f848e0a07d043c8aec2cbf254d65d079 Nov 23 22:56:26.087674 unknown[691]: fetched base config from "system" Nov 23 22:56:26.087689 unknown[691]: fetched user config from "qemu" Nov 23 22:56:26.088024 ignition[691]: fetch-offline: fetch-offline passed Nov 23 22:56:26.088095 ignition[691]: Ignition finished successfully Nov 23 22:56:26.090343 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 23 22:56:26.099422 systemd-networkd[800]: lo: Link UP Nov 23 22:56:26.099435 systemd-networkd[800]: lo: Gained carrier Nov 23 22:56:26.100244 systemd-networkd[800]: Enumeration completed Nov 23 22:56:26.100354 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 23 22:56:26.100700 systemd-networkd[800]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:56:26.100704 systemd-networkd[800]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 23 22:56:26.101513 systemd-networkd[800]: eth0: Link UP Nov 23 22:56:26.102001 systemd-networkd[800]: eth0: Gained carrier Nov 23 22:56:26.102013 systemd-networkd[800]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:56:26.102464 systemd[1]: Reached target network.target - Network. Nov 23 22:56:26.103902 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 23 22:56:26.106856 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 23 22:56:26.126725 systemd-networkd[800]: eth0: DHCPv4 address 10.0.0.41/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 23 22:56:26.138602 ignition[807]: Ignition 2.22.0 Nov 23 22:56:26.138624 ignition[807]: Stage: kargs Nov 23 22:56:26.138805 ignition[807]: no configs at "/usr/lib/ignition/base.d" Nov 23 22:56:26.138815 ignition[807]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:56:26.141633 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 23 22:56:26.139460 ignition[807]: kargs: kargs passed Nov 23 22:56:26.139519 ignition[807]: Ignition finished successfully Nov 23 22:56:26.144319 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 23 22:56:26.175681 ignition[816]: Ignition 2.22.0 Nov 23 22:56:26.175697 ignition[816]: Stage: disks Nov 23 22:56:26.175852 ignition[816]: no configs at "/usr/lib/ignition/base.d" Nov 23 22:56:26.175861 ignition[816]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:56:26.178225 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 23 22:56:26.176477 ignition[816]: disks: disks passed Nov 23 22:56:26.179783 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 23 22:56:26.176526 ignition[816]: Ignition finished successfully Nov 23 22:56:26.181502 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 23 22:56:26.182999 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 23 22:56:26.184639 systemd[1]: Reached target sysinit.target - System Initialization. Nov 23 22:56:26.186131 systemd[1]: Reached target basic.target - Basic System. Nov 23 22:56:26.188891 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 23 22:56:26.220409 systemd-resolved[297]: Detected conflict on linux IN A 10.0.0.41 Nov 23 22:56:26.220420 systemd-resolved[297]: Hostname conflict, changing published hostname from 'linux' to 'linux3'. Nov 23 22:56:26.223392 systemd-fsck[826]: ROOT: clean, 15/553520 files, 52789/553472 blocks Nov 23 22:56:26.226109 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 23 22:56:26.231149 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 23 22:56:26.293686 kernel: EXT4-fs (vda9): mounted filesystem c70a3a7b-80c4-4387-ab29-1bf940859b86 r/w with ordered data mode. Quota mode: none. Nov 23 22:56:26.294060 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 23 22:56:26.295239 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 23 22:56:26.297674 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 23 22:56:26.312959 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 23 22:56:26.314027 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 23 22:56:26.314075 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 23 22:56:26.314103 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 23 22:56:26.320758 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 23 22:56:26.326415 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (835) Nov 23 22:56:26.326438 kernel: BTRFS info (device vda6): first mount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:56:26.326455 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:56:26.324225 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 23 22:56:26.330677 kernel: BTRFS info (device vda6): turning on async discard Nov 23 22:56:26.330710 kernel: BTRFS info (device vda6): enabling free space tree Nov 23 22:56:26.332289 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 23 22:56:26.369371 initrd-setup-root[862]: cut: /sysroot/etc/passwd: No such file or directory Nov 23 22:56:26.373651 initrd-setup-root[869]: cut: /sysroot/etc/group: No such file or directory Nov 23 22:56:26.377820 initrd-setup-root[876]: cut: /sysroot/etc/shadow: No such file or directory Nov 23 22:56:26.381492 initrd-setup-root[883]: cut: /sysroot/etc/gshadow: No such file or directory Nov 23 22:56:26.455242 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 23 22:56:26.457746 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 23 22:56:26.459422 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 23 22:56:26.482694 kernel: BTRFS info (device vda6): last unmount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:56:26.494772 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 23 22:56:26.508733 ignition[952]: INFO : Ignition 2.22.0 Nov 23 22:56:26.508733 ignition[952]: INFO : Stage: mount Nov 23 22:56:26.510324 ignition[952]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 23 22:56:26.510324 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:56:26.510324 ignition[952]: INFO : mount: mount passed Nov 23 22:56:26.510324 ignition[952]: INFO : Ignition finished successfully Nov 23 22:56:26.510947 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 23 22:56:26.513644 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 23 22:56:26.902037 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 23 22:56:26.903708 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 23 22:56:26.925695 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (964) Nov 23 22:56:26.928202 kernel: BTRFS info (device vda6): first mount of filesystem b13f7cbd-5564-4927-b75d-d55dbc1bbfa7 Nov 23 22:56:26.928238 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 23 22:56:26.931301 kernel: BTRFS info (device vda6): turning on async discard Nov 23 22:56:26.931331 kernel: BTRFS info (device vda6): enabling free space tree Nov 23 22:56:26.932940 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 23 22:56:26.969277 ignition[981]: INFO : Ignition 2.22.0 Nov 23 22:56:26.969277 ignition[981]: INFO : Stage: files Nov 23 22:56:26.971119 ignition[981]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 23 22:56:26.971119 ignition[981]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:56:26.971119 ignition[981]: DEBUG : files: compiled without relabeling support, skipping Nov 23 22:56:26.971119 ignition[981]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 23 22:56:26.971119 ignition[981]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 23 22:56:26.977920 ignition[981]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 23 22:56:26.977920 ignition[981]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 23 22:56:26.977920 ignition[981]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 23 22:56:26.977920 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 23 22:56:26.977920 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 23 22:56:26.973784 unknown[981]: wrote ssh authorized keys file for user: core Nov 23 22:56:26.986888 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 23 22:56:26.986888 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 23 22:56:26.986888 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 23 22:56:26.986888 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 23 22:56:26.986888 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 23 22:56:26.986888 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Nov 23 22:56:27.212836 systemd-networkd[800]: eth0: Gained IPv6LL Nov 23 22:56:27.372645 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 23 22:56:27.674619 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 23 22:56:27.674619 ignition[981]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Nov 23 22:56:27.678099 ignition[981]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 23 22:56:27.687613 ignition[981]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 23 22:56:27.687613 ignition[981]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Nov 23 22:56:27.687613 ignition[981]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Nov 23 22:56:27.721771 ignition[981]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 23 22:56:27.725708 ignition[981]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 23 22:56:27.727276 ignition[981]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Nov 23 22:56:27.727276 ignition[981]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 23 22:56:27.727276 ignition[981]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 23 22:56:27.727276 ignition[981]: INFO : files: files passed Nov 23 22:56:27.727276 ignition[981]: INFO : Ignition finished successfully Nov 23 22:56:27.728962 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 23 22:56:27.734822 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 23 22:56:27.737208 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 23 22:56:27.754347 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 23 22:56:27.754498 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 23 22:56:27.757802 initrd-setup-root-after-ignition[1011]: grep: /sysroot/oem/oem-release: No such file or directory Nov 23 22:56:27.760367 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 23 22:56:27.760367 initrd-setup-root-after-ignition[1013]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 23 22:56:27.763397 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 23 22:56:27.764709 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 23 22:56:27.766464 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 23 22:56:27.768887 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 23 22:56:27.835069 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 23 22:56:27.835204 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 23 22:56:27.837403 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 23 22:56:27.839046 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 23 22:56:27.840651 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 23 22:56:27.841627 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 23 22:56:27.874430 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 23 22:56:27.877073 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 23 22:56:27.904411 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 23 22:56:27.905851 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 23 22:56:27.907888 systemd[1]: Stopped target timers.target - Timer Units. Nov 23 22:56:27.909785 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 23 22:56:27.909925 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 23 22:56:27.912891 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 23 22:56:27.914880 systemd[1]: Stopped target basic.target - Basic System. Nov 23 22:56:27.916560 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 23 22:56:27.918459 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 23 22:56:27.920469 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 23 22:56:27.922792 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 23 22:56:27.924760 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 23 22:56:27.926748 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 23 22:56:27.928798 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 23 22:56:27.930715 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 23 22:56:27.932634 systemd[1]: Stopped target swap.target - Swaps. Nov 23 22:56:27.934267 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 23 22:56:27.934406 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 23 22:56:27.936586 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 23 22:56:27.938700 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 23 22:56:27.940642 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 23 22:56:27.941749 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 23 22:56:27.942963 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 23 22:56:27.943096 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 23 22:56:27.945800 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 23 22:56:27.945928 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 23 22:56:27.947907 systemd[1]: Stopped target paths.target - Path Units. Nov 23 22:56:27.949388 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 23 22:56:27.949487 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 23 22:56:27.951353 systemd[1]: Stopped target slices.target - Slice Units. Nov 23 22:56:27.953237 systemd[1]: Stopped target sockets.target - Socket Units. Nov 23 22:56:27.954741 systemd[1]: iscsid.socket: Deactivated successfully. Nov 23 22:56:27.954833 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 23 22:56:27.956587 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 23 22:56:27.956679 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 23 22:56:27.958769 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 23 22:56:27.958891 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 23 22:56:27.960644 systemd[1]: ignition-files.service: Deactivated successfully. Nov 23 22:56:27.960765 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 23 22:56:27.963971 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 23 22:56:27.965401 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 23 22:56:27.965536 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 23 22:56:27.968387 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 23 22:56:27.970358 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 23 22:56:27.970482 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 23 22:56:27.972721 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 23 22:56:27.972826 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 23 22:56:27.979570 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 23 22:56:27.979694 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 23 22:56:27.994928 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 23 22:56:28.005499 ignition[1037]: INFO : Ignition 2.22.0 Nov 23 22:56:28.005499 ignition[1037]: INFO : Stage: umount Nov 23 22:56:28.007245 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 23 22:56:28.007245 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 23 22:56:28.010393 ignition[1037]: INFO : umount: umount passed Nov 23 22:56:28.010393 ignition[1037]: INFO : Ignition finished successfully Nov 23 22:56:28.010192 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 23 22:56:28.010301 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 23 22:56:28.011508 systemd[1]: Stopped target network.target - Network. Nov 23 22:56:28.012743 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 23 22:56:28.012815 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 23 22:56:28.014309 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 23 22:56:28.014361 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 23 22:56:28.015927 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 23 22:56:28.015977 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 23 22:56:28.017583 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 23 22:56:28.017625 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 23 22:56:28.019401 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 23 22:56:28.020966 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 23 22:56:28.031733 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 23 22:56:28.031918 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 23 22:56:28.039176 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 23 22:56:28.039481 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 23 22:56:28.039522 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 23 22:56:28.048770 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 23 22:56:28.049044 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 23 22:56:28.049157 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 23 22:56:28.052508 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 23 22:56:28.052743 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 23 22:56:28.052843 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 23 22:56:28.055683 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 23 22:56:28.056883 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 23 22:56:28.056928 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 23 22:56:28.058652 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 23 22:56:28.058778 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 23 22:56:28.061443 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 23 22:56:28.063337 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 23 22:56:28.063444 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 23 22:56:28.065459 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 23 22:56:28.065507 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 23 22:56:28.068081 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 23 22:56:28.068143 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 23 22:56:28.070094 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 23 22:56:28.073983 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 23 22:56:28.092360 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 23 22:56:28.096845 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 23 22:56:28.098252 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 23 22:56:28.098297 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 23 22:56:28.100131 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 23 22:56:28.100171 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 23 22:56:28.101864 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 23 22:56:28.101923 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 23 22:56:28.104567 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 23 22:56:28.104621 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 23 22:56:28.106529 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 23 22:56:28.106593 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 23 22:56:28.110327 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 23 22:56:28.111386 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 23 22:56:28.111472 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 23 22:56:28.116146 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 23 22:56:28.116202 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 23 22:56:28.119438 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 23 22:56:28.119492 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:56:28.123278 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 23 22:56:28.133814 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 23 22:56:28.139804 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 23 22:56:28.140725 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 23 22:56:28.142227 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 23 22:56:28.144643 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 23 22:56:28.167456 systemd[1]: Switching root. Nov 23 22:56:28.205420 systemd-journald[246]: Journal stopped Nov 23 22:56:29.120622 systemd-journald[246]: Received SIGTERM from PID 1 (systemd). Nov 23 22:56:29.120703 kernel: SELinux: policy capability network_peer_controls=1 Nov 23 22:56:29.120717 kernel: SELinux: policy capability open_perms=1 Nov 23 22:56:29.120731 kernel: SELinux: policy capability extended_socket_class=1 Nov 23 22:56:29.120742 kernel: SELinux: policy capability always_check_network=0 Nov 23 22:56:29.120753 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 23 22:56:29.120765 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 23 22:56:29.120774 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 23 22:56:29.120787 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 23 22:56:29.120797 kernel: SELinux: policy capability userspace_initial_context=0 Nov 23 22:56:29.120810 kernel: audit: type=1403 audit(1763938588.382:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 23 22:56:29.120825 systemd[1]: Successfully loaded SELinux policy in 69.188ms. Nov 23 22:56:29.120845 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.525ms. Nov 23 22:56:29.120857 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) Nov 23 22:56:29.120868 systemd[1]: Detected virtualization kvm. Nov 23 22:56:29.120878 systemd[1]: Detected architecture arm64. Nov 23 22:56:29.120888 systemd[1]: Detected first boot. Nov 23 22:56:29.120899 systemd[1]: Initializing machine ID from VM UUID. Nov 23 22:56:29.120910 zram_generator::config[1084]: No configuration found. Nov 23 22:56:29.120925 kernel: NET: Registered PF_VSOCK protocol family Nov 23 22:56:29.120935 systemd[1]: Populated /etc with preset unit settings. Nov 23 22:56:29.120946 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 23 22:56:29.120956 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 23 22:56:29.120967 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 23 22:56:29.120977 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 23 22:56:29.120987 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 23 22:56:29.120998 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 23 22:56:29.121008 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 23 22:56:29.121020 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 23 22:56:29.121030 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 23 22:56:29.121044 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 23 22:56:29.121058 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 23 22:56:29.121070 systemd[1]: Created slice user.slice - User and Session Slice. Nov 23 22:56:29.121081 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 23 22:56:29.121092 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 23 22:56:29.121102 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 23 22:56:29.121123 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 23 22:56:29.121138 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 23 22:56:29.121149 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 23 22:56:29.121159 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 23 22:56:29.121170 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 23 22:56:29.121181 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 23 22:56:29.121192 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 23 22:56:29.121206 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 23 22:56:29.121217 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 23 22:56:29.121228 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 23 22:56:29.121239 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 23 22:56:29.121249 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 23 22:56:29.121260 systemd[1]: Reached target slices.target - Slice Units. Nov 23 22:56:29.121270 systemd[1]: Reached target swap.target - Swaps. Nov 23 22:56:29.121281 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 23 22:56:29.121291 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 23 22:56:29.121302 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 23 22:56:29.121314 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 23 22:56:29.121324 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 23 22:56:29.121334 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 23 22:56:29.121344 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 23 22:56:29.121355 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 23 22:56:29.121365 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 23 22:56:29.121376 systemd[1]: Mounting media.mount - External Media Directory... Nov 23 22:56:29.121386 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 23 22:56:29.121397 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 23 22:56:29.121409 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 23 22:56:29.121420 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). Nov 23 22:56:29.121431 systemd[1]: Reached target machines.target - Containers. Nov 23 22:56:29.121441 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 23 22:56:29.121452 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 23 22:56:29.121463 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 23 22:56:29.121473 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 23 22:56:29.121484 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 23 22:56:29.121494 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 23 22:56:29.121506 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 23 22:56:29.121517 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 23 22:56:29.121527 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 23 22:56:29.121538 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 23 22:56:29.121548 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 23 22:56:29.121558 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 23 22:56:29.121569 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 23 22:56:29.121579 systemd[1]: Stopped systemd-fsck-usr.service. Nov 23 22:56:29.121592 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). Nov 23 22:56:29.121603 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 23 22:56:29.121614 kernel: loop: module loaded Nov 23 22:56:29.121624 kernel: fuse: init (API version 7.41) Nov 23 22:56:29.121634 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 23 22:56:29.121645 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 23 22:56:29.122199 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 23 22:56:29.122223 kernel: ACPI: bus type drm_connector registered Nov 23 22:56:29.122236 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 23 22:56:29.122252 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 23 22:56:29.122293 systemd-journald[1152]: Collecting audit messages is disabled. Nov 23 22:56:29.122318 systemd[1]: verity-setup.service: Deactivated successfully. Nov 23 22:56:29.122329 systemd[1]: Stopped verity-setup.service. Nov 23 22:56:29.122342 systemd-journald[1152]: Journal started Nov 23 22:56:29.122363 systemd-journald[1152]: Runtime Journal (/run/log/journal/56d1aea11e0b4a3486a0bb44852a6e7e) is 6M, max 48.5M, 42.4M free. Nov 23 22:56:29.132136 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 23 22:56:29.132206 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 23 22:56:29.132258 systemd[1]: Mounted media.mount - External Media Directory. Nov 23 22:56:29.132286 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 23 22:56:28.870532 systemd[1]: Queued start job for default target multi-user.target. Nov 23 22:56:28.885920 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 23 22:56:28.886363 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 23 22:56:29.135980 systemd[1]: Started systemd-journald.service - Journal Service. Nov 23 22:56:29.136963 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 23 22:56:29.138345 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 23 22:56:29.139796 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 23 22:56:29.141462 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 23 22:56:29.143261 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 23 22:56:29.143456 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 23 22:56:29.145002 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 23 22:56:29.145209 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 23 22:56:29.146724 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 23 22:56:29.146910 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 23 22:56:29.148392 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 23 22:56:29.148566 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 23 22:56:29.150446 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 23 22:56:29.150637 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 23 22:56:29.152128 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 23 22:56:29.152331 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 23 22:56:29.153849 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 23 22:56:29.155362 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 23 22:56:29.157103 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 23 22:56:29.158989 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 23 22:56:29.171674 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 23 22:56:29.174222 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 23 22:56:29.176716 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 23 22:56:29.178016 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 23 22:56:29.178051 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 23 22:56:29.180177 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 23 22:56:29.184840 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 23 22:56:29.186277 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 23 22:56:29.187999 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 23 22:56:29.190388 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 23 22:56:29.192022 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 23 22:56:29.193163 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 23 22:56:29.194632 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 23 22:56:29.195983 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 23 22:56:29.200356 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 23 22:56:29.201295 systemd-journald[1152]: Time spent on flushing to /var/log/journal/56d1aea11e0b4a3486a0bb44852a6e7e is 24.423ms for 868 entries. Nov 23 22:56:29.201295 systemd-journald[1152]: System Journal (/var/log/journal/56d1aea11e0b4a3486a0bb44852a6e7e) is 8M, max 195.6M, 187.6M free. Nov 23 22:56:29.249487 systemd-journald[1152]: Received client request to flush runtime journal. Nov 23 22:56:29.249588 kernel: loop0: detected capacity change from 0 to 119840 Nov 23 22:56:29.205966 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 23 22:56:29.213731 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 23 22:56:29.215610 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 23 22:56:29.217327 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 23 22:56:29.219238 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 23 22:56:29.232098 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 23 22:56:29.235274 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 23 22:56:29.254102 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 23 22:56:29.257638 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 23 22:56:29.269684 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 23 22:56:29.275731 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 23 22:56:29.279265 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 23 22:56:29.283267 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 23 22:56:29.289693 kernel: loop1: detected capacity change from 0 to 100632 Nov 23 22:56:29.311490 systemd-tmpfiles[1216]: ACLs are not supported, ignoring. Nov 23 22:56:29.311871 kernel: loop2: detected capacity change from 0 to 207008 Nov 23 22:56:29.311511 systemd-tmpfiles[1216]: ACLs are not supported, ignoring. Nov 23 22:56:29.315554 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 23 22:56:29.347682 kernel: loop3: detected capacity change from 0 to 119840 Nov 23 22:56:29.356790 kernel: loop4: detected capacity change from 0 to 100632 Nov 23 22:56:29.363744 kernel: loop5: detected capacity change from 0 to 207008 Nov 23 22:56:29.369939 (sd-merge)[1224]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Nov 23 22:56:29.370433 (sd-merge)[1224]: Merged extensions into '/usr'. Nov 23 22:56:29.375424 systemd[1]: Reload requested from client PID 1200 ('systemd-sysext') (unit systemd-sysext.service)... Nov 23 22:56:29.375579 systemd[1]: Reloading... Nov 23 22:56:29.427703 zram_generator::config[1249]: No configuration found. Nov 23 22:56:29.524726 ldconfig[1195]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 23 22:56:29.622533 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 23 22:56:29.622919 systemd[1]: Reloading finished in 246 ms. Nov 23 22:56:29.654646 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 23 22:56:29.657389 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 23 22:56:29.681244 systemd[1]: Starting ensure-sysext.service... Nov 23 22:56:29.683323 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 23 22:56:29.689340 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 23 22:56:29.693141 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 23 22:56:29.696623 systemd[1]: Reload requested from client PID 1284 ('systemctl') (unit ensure-sysext.service)... Nov 23 22:56:29.696641 systemd[1]: Reloading... Nov 23 22:56:29.700413 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 23 22:56:29.700446 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 23 22:56:29.700735 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 23 22:56:29.700956 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 23 22:56:29.701582 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 23 22:56:29.701808 systemd-tmpfiles[1285]: ACLs are not supported, ignoring. Nov 23 22:56:29.701855 systemd-tmpfiles[1285]: ACLs are not supported, ignoring. Nov 23 22:56:29.704975 systemd-tmpfiles[1285]: Detected autofs mount point /boot during canonicalization of boot. Nov 23 22:56:29.704985 systemd-tmpfiles[1285]: Skipping /boot Nov 23 22:56:29.711218 systemd-tmpfiles[1285]: Detected autofs mount point /boot during canonicalization of boot. Nov 23 22:56:29.711231 systemd-tmpfiles[1285]: Skipping /boot Nov 23 22:56:29.726076 systemd-udevd[1288]: Using default interface naming scheme 'v255'. Nov 23 22:56:29.750690 zram_generator::config[1312]: No configuration found. Nov 23 22:56:29.981291 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 23 22:56:29.982849 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 23 22:56:29.983266 systemd[1]: Reloading finished in 286 ms. Nov 23 22:56:30.001801 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 23 22:56:30.009714 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 23 22:56:30.033461 systemd[1]: Finished ensure-sysext.service. Nov 23 22:56:30.047717 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 23 22:56:30.050461 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 23 22:56:30.051793 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 23 22:56:30.065634 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 23 22:56:30.067966 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 23 22:56:30.078955 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 23 22:56:30.081934 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 23 22:56:30.083070 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 23 22:56:30.090851 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 23 22:56:30.092507 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). Nov 23 22:56:30.094055 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 23 22:56:30.099325 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 23 22:56:30.102713 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 23 22:56:30.106197 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 23 22:56:30.110031 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 23 22:56:30.113921 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 23 22:56:30.116424 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 23 22:56:30.119874 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 23 22:56:30.121791 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 23 22:56:30.121998 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 23 22:56:30.124272 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 23 22:56:30.124446 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 23 22:56:30.126830 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 23 22:56:30.127007 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 23 22:56:30.127260 augenrules[1430]: No rules Nov 23 22:56:30.128908 systemd[1]: audit-rules.service: Deactivated successfully. Nov 23 22:56:30.136903 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 23 22:56:30.140356 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 23 22:56:30.143505 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 23 22:56:30.149016 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 23 22:56:30.158855 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 23 22:56:30.160314 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 23 22:56:30.160387 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 23 22:56:30.161774 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 23 22:56:30.164539 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 23 22:56:30.165855 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). Nov 23 22:56:30.179947 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 23 22:56:30.185572 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 23 22:56:30.206014 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 23 22:56:30.273990 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 23 22:56:30.275536 systemd[1]: Reached target time-set.target - System Time Set. Nov 23 22:56:30.276260 systemd-networkd[1418]: lo: Link UP Nov 23 22:56:30.276272 systemd-networkd[1418]: lo: Gained carrier Nov 23 22:56:30.277349 systemd-networkd[1418]: Enumeration completed Nov 23 22:56:30.277471 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 23 22:56:30.277855 systemd-networkd[1418]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:56:30.277863 systemd-networkd[1418]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 23 22:56:30.278527 systemd-networkd[1418]: eth0: Link UP Nov 23 22:56:30.278643 systemd-networkd[1418]: eth0: Gained carrier Nov 23 22:56:30.278679 systemd-networkd[1418]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 23 22:56:30.280669 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 23 22:56:30.283323 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 23 22:56:30.283882 systemd-resolved[1421]: Positive Trust Anchors: Nov 23 22:56:30.283902 systemd-resolved[1421]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 23 22:56:30.283934 systemd-resolved[1421]: 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 Nov 23 22:56:30.290998 systemd-resolved[1421]: Defaulting to hostname 'linux'. Nov 23 22:56:30.292590 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 23 22:56:30.293906 systemd[1]: Reached target network.target - Network. Nov 23 22:56:30.294752 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 23 22:56:30.296541 systemd[1]: Reached target sysinit.target - System Initialization. Nov 23 22:56:30.297797 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 23 22:56:30.298846 systemd-networkd[1418]: eth0: DHCPv4 address 10.0.0.41/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 23 22:56:30.299113 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 23 22:56:30.299565 systemd-timesyncd[1423]: Network configuration changed, trying to establish connection. Nov 23 22:56:30.300745 systemd-timesyncd[1423]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 23 22:56:30.300818 systemd-timesyncd[1423]: Initial clock synchronization to Sun 2025-11-23 22:56:30.666217 UTC. Nov 23 22:56:30.300932 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 23 22:56:30.302011 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 23 22:56:30.303321 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 23 22:56:30.305068 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 23 22:56:30.305118 systemd[1]: Reached target paths.target - Path Units. Nov 23 22:56:30.306157 systemd[1]: Reached target timers.target - Timer Units. Nov 23 22:56:30.308475 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 23 22:56:30.311043 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 23 22:56:30.313983 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 23 22:56:30.315497 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 23 22:56:30.316794 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 23 22:56:30.337622 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 23 22:56:30.339084 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 23 22:56:30.342741 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 23 22:56:30.344289 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 23 22:56:30.346399 systemd[1]: Reached target sockets.target - Socket Units. Nov 23 22:56:30.347753 systemd[1]: Reached target basic.target - Basic System. Nov 23 22:56:30.348751 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 23 22:56:30.348791 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 23 22:56:30.350581 systemd[1]: Starting containerd.service - containerd container runtime... Nov 23 22:56:30.353491 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 23 22:56:30.371363 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 23 22:56:30.373832 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 23 22:56:30.376231 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 23 22:56:30.377422 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). Nov 23 22:56:30.378779 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 23 22:56:30.382133 jq[1470]: false Nov 23 22:56:30.382821 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 23 22:56:30.385088 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 23 22:56:30.388711 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 23 22:56:30.392032 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 23 22:56:30.392769 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 23 22:56:30.393754 systemd[1]: Starting update-engine.service - Update Engine... Nov 23 22:56:30.395214 extend-filesystems[1471]: Found /dev/vda6 Nov 23 22:56:30.397195 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 23 22:56:30.402133 extend-filesystems[1471]: Found /dev/vda9 Nov 23 22:56:30.403914 extend-filesystems[1471]: Checking size of /dev/vda9 Nov 23 22:56:30.407363 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 23 22:56:30.408924 jq[1484]: true Nov 23 22:56:30.409381 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 23 22:56:30.409602 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 23 22:56:30.409978 systemd[1]: motdgen.service: Deactivated successfully. Nov 23 22:56:30.410227 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 23 22:56:30.411801 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 23 22:56:30.412050 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 23 22:56:30.416058 extend-filesystems[1471]: Resized partition /dev/vda9 Nov 23 22:56:30.422265 extend-filesystems[1498]: resize2fs 1.47.3 (8-Jul-2025) Nov 23 22:56:30.437714 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Nov 23 22:56:30.437781 update_engine[1481]: I20251123 22:56:30.436266 1481 main.cc:92] Flatcar Update Engine starting Nov 23 22:56:30.431981 (ntainerd)[1497]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 23 22:56:30.438608 jq[1494]: true Nov 23 22:56:30.461676 dbus-daemon[1468]: [system] SELinux support is enabled Nov 23 22:56:30.462060 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 23 22:56:30.470708 update_engine[1481]: I20251123 22:56:30.468976 1481 update_check_scheduler.cc:74] Next update check in 9m8s Nov 23 22:56:30.469428 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). Nov 23 22:56:30.469460 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 23 22:56:30.472967 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). Nov 23 22:56:30.472999 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 23 22:56:30.480679 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Nov 23 22:56:30.480878 systemd[1]: Started update-engine.service - Update Engine. Nov 23 22:56:30.485693 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 23 22:56:30.496713 systemd-logind[1478]: Watching system buttons on /dev/input/event0 (Power Button) Nov 23 22:56:30.499055 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 23 22:56:30.499878 extend-filesystems[1498]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 23 22:56:30.499878 extend-filesystems[1498]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 23 22:56:30.499878 extend-filesystems[1498]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Nov 23 22:56:30.499464 systemd-logind[1478]: New seat seat0. Nov 23 22:56:30.511372 extend-filesystems[1471]: Resized filesystem in /dev/vda9 Nov 23 22:56:30.499666 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 23 22:56:30.505256 systemd[1]: Started systemd-logind.service - User Login Management. Nov 23 22:56:30.530133 bash[1526]: Updated "/home/core/.ssh/authorized_keys" Nov 23 22:56:30.534709 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 23 22:56:30.537321 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 23 22:56:30.538778 locksmithd[1510]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 23 22:56:30.607055 containerd[1497]: time="2025-11-23T22:56:30Z" 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 Nov 23 22:56:30.609249 containerd[1497]: time="2025-11-23T22:56:30.609157480Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Nov 23 22:56:30.621680 containerd[1497]: time="2025-11-23T22:56:30.621278880Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="12.32µs" Nov 23 22:56:30.621680 containerd[1497]: time="2025-11-23T22:56:30.621331720Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 23 22:56:30.621680 containerd[1497]: time="2025-11-23T22:56:30.621371840Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 23 22:56:30.621680 containerd[1497]: time="2025-11-23T22:56:30.621558840Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 23 22:56:30.621680 containerd[1497]: time="2025-11-23T22:56:30.621576440Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 23 22:56:30.621680 containerd[1497]: time="2025-11-23T22:56:30.621607160Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:56:30.621971 containerd[1497]: time="2025-11-23T22:56:30.621941760Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 23 22:56:30.622024 containerd[1497]: time="2025-11-23T22:56:30.622010280Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 23 22:56:30.622363 containerd[1497]: time="2025-11-23T22:56:30.622333960Z" 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 Nov 23 22:56:30.622486 containerd[1497]: time="2025-11-23T22:56:30.622467840Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:56:30.622540 containerd[1497]: time="2025-11-23T22:56:30.622527240Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 23 22:56:30.622589 containerd[1497]: time="2025-11-23T22:56:30.622574280Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 23 22:56:30.622761 containerd[1497]: time="2025-11-23T22:56:30.622740960Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 23 22:56:30.623056 containerd[1497]: time="2025-11-23T22:56:30.623028360Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 23 22:56:30.623182 containerd[1497]: time="2025-11-23T22:56:30.623163240Z" 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 Nov 23 22:56:30.623233 containerd[1497]: time="2025-11-23T22:56:30.623220960Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 23 22:56:30.623330 containerd[1497]: time="2025-11-23T22:56:30.623313400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 23 22:56:30.623633 containerd[1497]: time="2025-11-23T22:56:30.623612640Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 23 22:56:30.623877 containerd[1497]: time="2025-11-23T22:56:30.623855160Z" level=info msg="metadata content store policy set" policy=shared Nov 23 22:56:30.628126 containerd[1497]: time="2025-11-23T22:56:30.628077240Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 23 22:56:30.628291 containerd[1497]: time="2025-11-23T22:56:30.628272680Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 23 22:56:30.628345 containerd[1497]: time="2025-11-23T22:56:30.628333680Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 23 22:56:30.628418 containerd[1497]: time="2025-11-23T22:56:30.628404480Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 23 22:56:30.628466 containerd[1497]: time="2025-11-23T22:56:30.628455520Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 23 22:56:30.628514 containerd[1497]: time="2025-11-23T22:56:30.628502000Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 23 22:56:30.628565 containerd[1497]: time="2025-11-23T22:56:30.628553160Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 23 22:56:30.628615 containerd[1497]: time="2025-11-23T22:56:30.628603800Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 23 22:56:30.628702 containerd[1497]: time="2025-11-23T22:56:30.628687600Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 23 22:56:30.628753 containerd[1497]: time="2025-11-23T22:56:30.628741680Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 23 22:56:30.628815 containerd[1497]: time="2025-11-23T22:56:30.628801840Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 23 22:56:30.628876 containerd[1497]: time="2025-11-23T22:56:30.628863280Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 23 22:56:30.629076 containerd[1497]: time="2025-11-23T22:56:30.629057080Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 23 22:56:30.629161 containerd[1497]: time="2025-11-23T22:56:30.629146040Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 23 22:56:30.629225 containerd[1497]: time="2025-11-23T22:56:30.629212600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 23 22:56:30.629276 containerd[1497]: time="2025-11-23T22:56:30.629263240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 23 22:56:30.629337 containerd[1497]: time="2025-11-23T22:56:30.629323320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 23 22:56:30.629385 containerd[1497]: time="2025-11-23T22:56:30.629374360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 23 22:56:30.629440 containerd[1497]: time="2025-11-23T22:56:30.629427200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 23 22:56:30.629498 containerd[1497]: time="2025-11-23T22:56:30.629485560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 23 22:56:30.629554 containerd[1497]: time="2025-11-23T22:56:30.629542200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 23 22:56:30.629605 containerd[1497]: time="2025-11-23T22:56:30.629592520Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 23 22:56:30.629653 containerd[1497]: time="2025-11-23T22:56:30.629640920Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 23 22:56:30.629926 containerd[1497]: time="2025-11-23T22:56:30.629907600Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 23 22:56:30.629984 containerd[1497]: time="2025-11-23T22:56:30.629972520Z" level=info msg="Start snapshots syncer" Nov 23 22:56:30.630057 containerd[1497]: time="2025-11-23T22:56:30.630041680Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 23 22:56:30.630390 containerd[1497]: time="2025-11-23T22:56:30.630351840Z" 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\"}" Nov 23 22:56:30.630555 containerd[1497]: time="2025-11-23T22:56:30.630537440Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 23 22:56:30.630696 containerd[1497]: time="2025-11-23T22:56:30.630670920Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 23 22:56:30.630885 containerd[1497]: time="2025-11-23T22:56:30.630863120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 23 22:56:30.630960 containerd[1497]: time="2025-11-23T22:56:30.630945240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 23 22:56:30.631009 containerd[1497]: time="2025-11-23T22:56:30.630996400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 23 22:56:30.631061 containerd[1497]: time="2025-11-23T22:56:30.631048720Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 23 22:56:30.631124 containerd[1497]: time="2025-11-23T22:56:30.631099080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 23 22:56:30.631175 containerd[1497]: time="2025-11-23T22:56:30.631163680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 23 22:56:30.631241 containerd[1497]: time="2025-11-23T22:56:30.631227960Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 23 22:56:30.631313 containerd[1497]: time="2025-11-23T22:56:30.631299960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 23 22:56:30.631369 containerd[1497]: time="2025-11-23T22:56:30.631356200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 23 22:56:30.631420 containerd[1497]: time="2025-11-23T22:56:30.631407400Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 23 22:56:30.631522 containerd[1497]: time="2025-11-23T22:56:30.631506920Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 23 22:56:30.631584 containerd[1497]: time="2025-11-23T22:56:30.631568920Z" 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 Nov 23 22:56:30.631638 containerd[1497]: time="2025-11-23T22:56:30.631625240Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:56:30.631781 containerd[1497]: time="2025-11-23T22:56:30.631754480Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 23 22:56:30.631839 containerd[1497]: time="2025-11-23T22:56:30.631825120Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 23 22:56:30.631894 containerd[1497]: time="2025-11-23T22:56:30.631882520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 23 22:56:30.631943 containerd[1497]: time="2025-11-23T22:56:30.631931440Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 23 22:56:30.632061 containerd[1497]: time="2025-11-23T22:56:30.632050480Z" level=info msg="runtime interface created" Nov 23 22:56:30.632100 containerd[1497]: time="2025-11-23T22:56:30.632089800Z" level=info msg="created NRI interface" Nov 23 22:56:30.632175 containerd[1497]: time="2025-11-23T22:56:30.632160720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 23 22:56:30.632227 containerd[1497]: time="2025-11-23T22:56:30.632216600Z" level=info msg="Connect containerd service" Nov 23 22:56:30.632295 containerd[1497]: time="2025-11-23T22:56:30.632282480Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 23 22:56:30.633211 containerd[1497]: time="2025-11-23T22:56:30.633176520Z" 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" Nov 23 22:56:30.735813 containerd[1497]: time="2025-11-23T22:56:30.735759920Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 23 22:56:30.735924 containerd[1497]: time="2025-11-23T22:56:30.735827160Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 23 22:56:30.735924 containerd[1497]: time="2025-11-23T22:56:30.735862800Z" level=info msg="Start subscribing containerd event" Nov 23 22:56:30.735924 containerd[1497]: time="2025-11-23T22:56:30.735913120Z" level=info msg="Start recovering state" Nov 23 22:56:30.736017 containerd[1497]: time="2025-11-23T22:56:30.735993400Z" level=info msg="Start event monitor" Nov 23 22:56:30.736017 containerd[1497]: time="2025-11-23T22:56:30.736007400Z" level=info msg="Start cni network conf syncer for default" Nov 23 22:56:30.736017 containerd[1497]: time="2025-11-23T22:56:30.736015520Z" level=info msg="Start streaming server" Nov 23 22:56:30.736066 containerd[1497]: time="2025-11-23T22:56:30.736024000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 23 22:56:30.736066 containerd[1497]: time="2025-11-23T22:56:30.736030680Z" level=info msg="runtime interface starting up..." Nov 23 22:56:30.736066 containerd[1497]: time="2025-11-23T22:56:30.736036280Z" level=info msg="starting plugins..." Nov 23 22:56:30.736066 containerd[1497]: time="2025-11-23T22:56:30.736048440Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 23 22:56:30.736481 containerd[1497]: time="2025-11-23T22:56:30.736437880Z" level=info msg="containerd successfully booted in 0.129923s" Nov 23 22:56:30.736913 systemd[1]: Started containerd.service - containerd container runtime. Nov 23 22:56:31.820940 systemd-networkd[1418]: eth0: Gained IPv6LL Nov 23 22:56:31.823878 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 23 22:56:31.826161 systemd[1]: Reached target network-online.target - Network is Online. Nov 23 22:56:31.829330 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 23 22:56:31.832384 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 23 22:56:31.837091 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 23 22:56:31.875284 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 23 22:56:31.877239 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 23 22:56:31.878976 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 23 22:56:31.881165 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 23 22:56:32.021301 sshd_keygen[1493]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 23 22:56:32.044558 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 23 22:56:32.047659 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 23 22:56:32.065628 systemd[1]: issuegen.service: Deactivated successfully. Nov 23 22:56:32.065927 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 23 22:56:32.071255 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 23 22:56:32.095798 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 23 22:56:32.100659 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 23 22:56:32.103647 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 23 22:56:32.105482 systemd[1]: Reached target getty.target - Login Prompts. Nov 23 22:56:32.520468 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 23 22:56:32.522227 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 23 22:56:32.526631 (kubelet)[1593]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 23 22:56:32.527970 systemd[1]: Startup finished in 2.152s (kernel) + 4.740s (initrd) + 4.214s (userspace) = 11.107s. Nov 23 22:56:32.954877 kubelet[1593]: E1123 22:56:32.954735 1593 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" Nov 23 22:56:32.957600 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 23 22:56:32.957775 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 23 22:56:32.958127 systemd[1]: kubelet.service: Consumed 768ms CPU time, 257.3M memory peak. Nov 23 22:56:36.777882 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 23 22:56:36.783156 systemd[1]: Started sshd@0-10.0.0.41:22-10.0.0.1:45068.service - OpenSSH per-connection server daemon (10.0.0.1:45068). Nov 23 22:56:36.900253 sshd[1607]: Accepted publickey for core from 10.0.0.1 port 45068 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:56:36.904488 sshd-session[1607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:56:36.919730 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 23 22:56:36.924767 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 23 22:56:36.929279 systemd-logind[1478]: New session 1 of user core. Nov 23 22:56:36.960201 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 23 22:56:36.968450 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 23 22:56:36.995514 (systemd)[1612]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 23 22:56:36.999332 systemd-logind[1478]: New session c1 of user core. Nov 23 22:56:37.140206 systemd[1612]: Queued start job for default target default.target. Nov 23 22:56:37.149987 systemd[1612]: Created slice app.slice - User Application Slice. Nov 23 22:56:37.150020 systemd[1612]: Reached target paths.target - Paths. Nov 23 22:56:37.150065 systemd[1612]: Reached target timers.target - Timers. Nov 23 22:56:37.151561 systemd[1612]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 23 22:56:37.169636 systemd[1612]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 23 22:56:37.169792 systemd[1612]: Reached target sockets.target - Sockets. Nov 23 22:56:37.169843 systemd[1612]: Reached target basic.target - Basic System. Nov 23 22:56:37.169874 systemd[1612]: Reached target default.target - Main User Target. Nov 23 22:56:37.169903 systemd[1612]: Startup finished in 160ms. Nov 23 22:56:37.170174 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 23 22:56:37.172842 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 23 22:56:37.239289 systemd[1]: Started sshd@1-10.0.0.41:22-10.0.0.1:45074.service - OpenSSH per-connection server daemon (10.0.0.1:45074). Nov 23 22:56:37.316664 sshd[1623]: Accepted publickey for core from 10.0.0.1 port 45074 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:56:37.318803 sshd-session[1623]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:56:37.324993 systemd-logind[1478]: New session 2 of user core. Nov 23 22:56:37.340954 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 23 22:56:37.396408 sshd[1626]: Connection closed by 10.0.0.1 port 45074 Nov 23 22:56:37.396749 sshd-session[1623]: pam_unix(sshd:session): session closed for user core Nov 23 22:56:37.408774 systemd[1]: sshd@1-10.0.0.41:22-10.0.0.1:45074.service: Deactivated successfully. Nov 23 22:56:37.410573 systemd[1]: session-2.scope: Deactivated successfully. Nov 23 22:56:37.413518 systemd-logind[1478]: Session 2 logged out. Waiting for processes to exit. Nov 23 22:56:37.415991 systemd[1]: Started sshd@2-10.0.0.41:22-10.0.0.1:45082.service - OpenSSH per-connection server daemon (10.0.0.1:45082). Nov 23 22:56:37.417007 systemd-logind[1478]: Removed session 2. Nov 23 22:56:37.479286 sshd[1632]: Accepted publickey for core from 10.0.0.1 port 45082 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:56:37.479953 sshd-session[1632]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:56:37.484196 systemd-logind[1478]: New session 3 of user core. Nov 23 22:56:37.494917 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 23 22:56:37.544437 sshd[1636]: Connection closed by 10.0.0.1 port 45082 Nov 23 22:56:37.544290 sshd-session[1632]: pam_unix(sshd:session): session closed for user core Nov 23 22:56:37.555582 systemd[1]: sshd@2-10.0.0.41:22-10.0.0.1:45082.service: Deactivated successfully. Nov 23 22:56:37.557265 systemd[1]: session-3.scope: Deactivated successfully. Nov 23 22:56:37.558072 systemd-logind[1478]: Session 3 logged out. Waiting for processes to exit. Nov 23 22:56:37.560608 systemd[1]: Started sshd@3-10.0.0.41:22-10.0.0.1:45086.service - OpenSSH per-connection server daemon (10.0.0.1:45086). Nov 23 22:56:37.561594 systemd-logind[1478]: Removed session 3. Nov 23 22:56:37.621934 sshd[1642]: Accepted publickey for core from 10.0.0.1 port 45086 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:56:37.623811 sshd-session[1642]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:56:37.630026 systemd-logind[1478]: New session 4 of user core. Nov 23 22:56:37.635912 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 23 22:56:37.694127 sshd[1645]: Connection closed by 10.0.0.1 port 45086 Nov 23 22:56:37.694631 sshd-session[1642]: pam_unix(sshd:session): session closed for user core Nov 23 22:56:37.711805 systemd[1]: sshd@3-10.0.0.41:22-10.0.0.1:45086.service: Deactivated successfully. Nov 23 22:56:37.713525 systemd[1]: session-4.scope: Deactivated successfully. Nov 23 22:56:37.714403 systemd-logind[1478]: Session 4 logged out. Waiting for processes to exit. Nov 23 22:56:37.717054 systemd-logind[1478]: Removed session 4. Nov 23 22:56:37.718894 systemd[1]: Started sshd@4-10.0.0.41:22-10.0.0.1:45088.service - OpenSSH per-connection server daemon (10.0.0.1:45088). Nov 23 22:56:37.781488 sshd[1651]: Accepted publickey for core from 10.0.0.1 port 45088 ssh2: RSA SHA256:QxoOoLvgP9E+zipnRJ4K0FLuuw/ehjwLMaCJR2ynZa8 Nov 23 22:56:37.782974 sshd-session[1651]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 23 22:56:37.787908 systemd-logind[1478]: New session 5 of user core. Nov 23 22:56:37.799958 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 23 22:56:37.860874 sudo[1655]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 23 22:56:37.861143 sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 23 22:56:37.873713 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 23 22:56:37.929374 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 23 22:56:37.929588 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 23 22:56:38.395253 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 23 22:56:38.395406 systemd[1]: kubelet.service: Consumed 768ms CPU time, 257.3M memory peak. Nov 23 22:56:38.397510 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 23 22:56:38.424167 systemd[1]: Reload requested from client PID 1694 ('systemctl') (unit session-5.scope)... Nov 23 22:56:38.424189 systemd[1]: Reloading... Nov 23 22:56:38.499710 zram_generator::config[1735]: No configuration found. Nov 23 22:56:38.777936 systemd[1]: Reloading finished in 352 ms. Nov 23 22:56:38.824997 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Nov 23 22:56:38.827940 systemd[1]: kubelet.service: Deactivated successfully. Nov 23 22:56:38.828279 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 23 22:56:38.828399 systemd[1]: kubelet.service: Consumed 110ms CPU time, 95.2M memory peak. Nov 23 22:56:38.830169 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 23 22:56:38.971932 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 23 22:56:38.978092 (kubelet)[1784]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 23 22:56:39.015177 kubelet[1784]: 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. Nov 23 22:56:39.015177 kubelet[1784]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 23 22:56:39.015177 kubelet[1784]: 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. Nov 23 22:56:39.015516 kubelet[1784]: I1123 22:56:39.015276 1784 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 23 22:56:39.868911 kubelet[1784]: I1123 22:56:39.868857 1784 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Nov 23 22:56:39.868911 kubelet[1784]: I1123 22:56:39.868896 1784 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 23 22:56:39.869224 kubelet[1784]: I1123 22:56:39.869208 1784 server.go:954] "Client rotation is on, will bootstrap in background" Nov 23 22:56:39.900430 kubelet[1784]: I1123 22:56:39.900060 1784 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 23 22:56:39.908426 kubelet[1784]: I1123 22:56:39.908393 1784 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Nov 23 22:56:39.911474 kubelet[1784]: I1123 22:56:39.911449 1784 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 23 22:56:39.912710 kubelet[1784]: I1123 22:56:39.912311 1784 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 23 22:56:39.912710 kubelet[1784]: I1123 22:56:39.912360 1784 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.41","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 23 22:56:39.912899 kubelet[1784]: I1123 22:56:39.912722 1784 topology_manager.go:138] "Creating topology manager with none policy" Nov 23 22:56:39.912899 kubelet[1784]: I1123 22:56:39.912735 1784 container_manager_linux.go:304] "Creating device plugin manager" Nov 23 22:56:39.913314 kubelet[1784]: I1123 22:56:39.913282 1784 state_mem.go:36] "Initialized new in-memory state store" Nov 23 22:56:39.917971 kubelet[1784]: I1123 22:56:39.917937 1784 kubelet.go:446] "Attempting to sync node with API server" Nov 23 22:56:39.917971 kubelet[1784]: I1123 22:56:39.917972 1784 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 23 22:56:39.918071 kubelet[1784]: I1123 22:56:39.918000 1784 kubelet.go:352] "Adding apiserver pod source" Nov 23 22:56:39.918071 kubelet[1784]: I1123 22:56:39.918010 1784 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 23 22:56:39.918260 kubelet[1784]: E1123 22:56:39.918144 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:39.918309 kubelet[1784]: E1123 22:56:39.918194 1784 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:39.922687 kubelet[1784]: I1123 22:56:39.922643 1784 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.7" apiVersion="v1" Nov 23 22:56:39.923590 kubelet[1784]: I1123 22:56:39.923560 1784 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 23 22:56:39.923781 kubelet[1784]: W1123 22:56:39.923766 1784 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 23 22:56:39.924934 kubelet[1784]: I1123 22:56:39.924912 1784 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 23 22:56:39.925011 kubelet[1784]: I1123 22:56:39.924955 1784 server.go:1287] "Started kubelet" Nov 23 22:56:39.925096 kubelet[1784]: I1123 22:56:39.925066 1784 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Nov 23 22:56:39.926063 kubelet[1784]: I1123 22:56:39.926042 1784 server.go:479] "Adding debug handlers to kubelet server" Nov 23 22:56:39.926461 kubelet[1784]: I1123 22:56:39.926393 1784 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 23 22:56:39.926834 kubelet[1784]: I1123 22:56:39.926810 1784 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 23 22:56:39.928968 kubelet[1784]: I1123 22:56:39.928939 1784 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 23 22:56:39.929124 kubelet[1784]: I1123 22:56:39.929000 1784 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 23 22:56:39.931638 kubelet[1784]: I1123 22:56:39.930249 1784 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 23 22:56:39.931638 kubelet[1784]: E1123 22:56:39.930351 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:39.931638 kubelet[1784]: I1123 22:56:39.931287 1784 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 23 22:56:39.931638 kubelet[1784]: I1123 22:56:39.931370 1784 reconciler.go:26] "Reconciler: start to sync state" Nov 23 22:56:39.933482 kubelet[1784]: E1123 22:56:39.933444 1784 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 23 22:56:39.933629 kubelet[1784]: I1123 22:56:39.933448 1784 factory.go:221] Registration of the systemd container factory successfully Nov 23 22:56:39.933810 kubelet[1784]: I1123 22:56:39.933790 1784 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 23 22:56:39.934948 kubelet[1784]: I1123 22:56:39.934920 1784 factory.go:221] Registration of the containerd container factory successfully Nov 23 22:56:39.946329 kubelet[1784]: E1123 22:56:39.946298 1784 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.41\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Nov 23 22:56:39.947760 kubelet[1784]: E1123 22:56:39.946284 1784 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.41.187ac4cf9620149a default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.41,UID:10.0.0.41,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.41,},FirstTimestamp:2025-11-23 22:56:39.92492969 +0000 UTC m=+0.943222178,LastTimestamp:2025-11-23 22:56:39.92492969 +0000 UTC m=+0.943222178,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.41,}" Nov 23 22:56:39.948393 kubelet[1784]: W1123 22:56:39.946936 1784 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.41" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 23 22:56:39.948533 kubelet[1784]: E1123 22:56:39.948507 1784 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.41\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 23 22:56:39.948726 kubelet[1784]: W1123 22:56:39.947011 1784 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Nov 23 22:56:39.948833 kubelet[1784]: E1123 22:56:39.948816 1784 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" Nov 23 22:56:39.948907 kubelet[1784]: I1123 22:56:39.948595 1784 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 23 22:56:39.948961 kubelet[1784]: I1123 22:56:39.948951 1784 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 23 22:56:39.949054 kubelet[1784]: I1123 22:56:39.949046 1784 state_mem.go:36] "Initialized new in-memory state store" Nov 23 22:56:39.949445 kubelet[1784]: W1123 22:56:39.948029 1784 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Nov 23 22:56:39.949497 kubelet[1784]: E1123 22:56:39.949457 1784 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 23 22:56:40.031186 kubelet[1784]: E1123 22:56:40.031135 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.058553 kubelet[1784]: I1123 22:56:40.058497 1784 policy_none.go:49] "None policy: Start" Nov 23 22:56:40.058553 kubelet[1784]: I1123 22:56:40.058536 1784 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 23 22:56:40.058553 kubelet[1784]: I1123 22:56:40.058550 1784 state_mem.go:35] "Initializing new in-memory state store" Nov 23 22:56:40.066592 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 23 22:56:40.082181 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 23 22:56:40.082812 kubelet[1784]: I1123 22:56:40.082751 1784 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 23 22:56:40.083996 kubelet[1784]: I1123 22:56:40.083949 1784 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 23 22:56:40.083996 kubelet[1784]: I1123 22:56:40.083986 1784 status_manager.go:227] "Starting to sync pod status with apiserver" Nov 23 22:56:40.084085 kubelet[1784]: I1123 22:56:40.084006 1784 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 23 22:56:40.084085 kubelet[1784]: I1123 22:56:40.084012 1784 kubelet.go:2382] "Starting kubelet main sync loop" Nov 23 22:56:40.084085 kubelet[1784]: E1123 22:56:40.084057 1784 kubelet.go:2406] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 23 22:56:40.092435 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 23 22:56:40.094374 kubelet[1784]: I1123 22:56:40.094330 1784 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 23 22:56:40.094635 kubelet[1784]: I1123 22:56:40.094563 1784 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 23 22:56:40.094635 kubelet[1784]: I1123 22:56:40.094590 1784 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 23 22:56:40.095614 kubelet[1784]: I1123 22:56:40.095267 1784 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 23 22:56:40.095917 kubelet[1784]: E1123 22:56:40.095889 1784 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 23 22:56:40.095951 kubelet[1784]: E1123 22:56:40.095938 1784 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.41\" not found" Nov 23 22:56:40.162418 kubelet[1784]: E1123 22:56:40.161742 1784 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.41\" not found" node="10.0.0.41" Nov 23 22:56:40.196682 kubelet[1784]: I1123 22:56:40.196245 1784 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.41" Nov 23 22:56:40.204584 kubelet[1784]: I1123 22:56:40.204542 1784 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.41" Nov 23 22:56:40.204584 kubelet[1784]: E1123 22:56:40.204579 1784 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.41\": node \"10.0.0.41\" not found" Nov 23 22:56:40.222119 kubelet[1784]: E1123 22:56:40.222078 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.322758 kubelet[1784]: E1123 22:56:40.322709 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.423831 kubelet[1784]: E1123 22:56:40.423702 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.524768 kubelet[1784]: E1123 22:56:40.524724 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.583935 sudo[1655]: pam_unix(sudo:session): session closed for user root Nov 23 22:56:40.585442 sshd[1654]: Connection closed by 10.0.0.1 port 45088 Nov 23 22:56:40.585867 sshd-session[1651]: pam_unix(sshd:session): session closed for user core Nov 23 22:56:40.590039 systemd[1]: sshd@4-10.0.0.41:22-10.0.0.1:45088.service: Deactivated successfully. Nov 23 22:56:40.592549 systemd[1]: session-5.scope: Deactivated successfully. Nov 23 22:56:40.593763 systemd[1]: session-5.scope: Consumed 421ms CPU time, 75.6M memory peak. Nov 23 22:56:40.595132 systemd-logind[1478]: Session 5 logged out. Waiting for processes to exit. Nov 23 22:56:40.596718 systemd-logind[1478]: Removed session 5. Nov 23 22:56:40.624884 kubelet[1784]: E1123 22:56:40.624828 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.725865 kubelet[1784]: E1123 22:56:40.725720 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.827118 kubelet[1784]: E1123 22:56:40.827051 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:40.873942 kubelet[1784]: I1123 22:56:40.873878 1784 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 23 22:56:40.874086 kubelet[1784]: W1123 22:56:40.874071 1784 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 23 22:56:40.919087 kubelet[1784]: E1123 22:56:40.919019 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:40.927694 kubelet[1784]: E1123 22:56:40.927635 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:41.028170 kubelet[1784]: E1123 22:56:41.028026 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:41.128341 kubelet[1784]: E1123 22:56:41.128287 1784 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.41\" not found" Nov 23 22:56:41.229510 kubelet[1784]: I1123 22:56:41.229472 1784 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 23 22:56:41.229949 containerd[1497]: time="2025-11-23T22:56:41.229817888Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 23 22:56:41.230370 kubelet[1784]: I1123 22:56:41.230075 1784 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 23 22:56:41.919716 kubelet[1784]: I1123 22:56:41.919643 1784 apiserver.go:52] "Watching apiserver" Nov 23 22:56:41.919716 kubelet[1784]: E1123 22:56:41.919705 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:41.931641 kubelet[1784]: I1123 22:56:41.931596 1784 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 23 22:56:41.936301 systemd[1]: Created slice kubepods-besteffort-podf4c349f9_15c2_48b1_b084_41eab341003d.slice - libcontainer container kubepods-besteffort-podf4c349f9_15c2_48b1_b084_41eab341003d.slice. Nov 23 22:56:41.944892 kubelet[1784]: I1123 22:56:41.944851 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/f4c349f9-15c2-48b1-b084-41eab341003d-kube-proxy\") pod \"kube-proxy-8vss5\" (UID: \"f4c349f9-15c2-48b1-b084-41eab341003d\") " pod="kube-system/kube-proxy-8vss5" Nov 23 22:56:41.944892 kubelet[1784]: I1123 22:56:41.944890 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/b677327f-8583-4f54-980e-af36bf9f6beb-cni\") pod \"kube-flannel-ds-96mdd\" (UID: \"b677327f-8583-4f54-980e-af36bf9f6beb\") " pod="kube-flannel/kube-flannel-ds-96mdd" Nov 23 22:56:41.945035 kubelet[1784]: I1123 22:56:41.944919 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/b677327f-8583-4f54-980e-af36bf9f6beb-flannel-cfg\") pod \"kube-flannel-ds-96mdd\" (UID: \"b677327f-8583-4f54-980e-af36bf9f6beb\") " pod="kube-flannel/kube-flannel-ds-96mdd" Nov 23 22:56:41.945035 kubelet[1784]: I1123 22:56:41.944954 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2wgw4\" (UniqueName: \"kubernetes.io/projected/b677327f-8583-4f54-980e-af36bf9f6beb-kube-api-access-2wgw4\") pod \"kube-flannel-ds-96mdd\" (UID: \"b677327f-8583-4f54-980e-af36bf9f6beb\") " pod="kube-flannel/kube-flannel-ds-96mdd" Nov 23 22:56:41.945035 kubelet[1784]: I1123 22:56:41.944975 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f4c349f9-15c2-48b1-b084-41eab341003d-xtables-lock\") pod \"kube-proxy-8vss5\" (UID: \"f4c349f9-15c2-48b1-b084-41eab341003d\") " pod="kube-system/kube-proxy-8vss5" Nov 23 22:56:41.945035 kubelet[1784]: I1123 22:56:41.944998 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/f4c349f9-15c2-48b1-b084-41eab341003d-lib-modules\") pod \"kube-proxy-8vss5\" (UID: \"f4c349f9-15c2-48b1-b084-41eab341003d\") " pod="kube-system/kube-proxy-8vss5" Nov 23 22:56:41.945133 kubelet[1784]: I1123 22:56:41.945037 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dnkxn\" (UniqueName: \"kubernetes.io/projected/f4c349f9-15c2-48b1-b084-41eab341003d-kube-api-access-dnkxn\") pod \"kube-proxy-8vss5\" (UID: \"f4c349f9-15c2-48b1-b084-41eab341003d\") " pod="kube-system/kube-proxy-8vss5" Nov 23 22:56:41.945133 kubelet[1784]: I1123 22:56:41.945066 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/b677327f-8583-4f54-980e-af36bf9f6beb-run\") pod \"kube-flannel-ds-96mdd\" (UID: \"b677327f-8583-4f54-980e-af36bf9f6beb\") " pod="kube-flannel/kube-flannel-ds-96mdd" Nov 23 22:56:41.945133 kubelet[1784]: I1123 22:56:41.945083 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/b677327f-8583-4f54-980e-af36bf9f6beb-cni-plugin\") pod \"kube-flannel-ds-96mdd\" (UID: \"b677327f-8583-4f54-980e-af36bf9f6beb\") " pod="kube-flannel/kube-flannel-ds-96mdd" Nov 23 22:56:41.945133 kubelet[1784]: I1123 22:56:41.945103 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b677327f-8583-4f54-980e-af36bf9f6beb-xtables-lock\") pod \"kube-flannel-ds-96mdd\" (UID: \"b677327f-8583-4f54-980e-af36bf9f6beb\") " pod="kube-flannel/kube-flannel-ds-96mdd" Nov 23 22:56:41.950477 systemd[1]: Created slice kubepods-burstable-podb677327f_8583_4f54_980e_af36bf9f6beb.slice - libcontainer container kubepods-burstable-podb677327f_8583_4f54_980e_af36bf9f6beb.slice. Nov 23 22:56:42.247509 containerd[1497]: time="2025-11-23T22:56:42.247231850Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8vss5,Uid:f4c349f9-15c2-48b1-b084-41eab341003d,Namespace:kube-system,Attempt:0,}" Nov 23 22:56:42.264030 containerd[1497]: time="2025-11-23T22:56:42.263986948Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-96mdd,Uid:b677327f-8583-4f54-980e-af36bf9f6beb,Namespace:kube-flannel,Attempt:0,}" Nov 23 22:56:42.776409 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount408524582.mount: Deactivated successfully. Nov 23 22:56:42.785954 containerd[1497]: time="2025-11-23T22:56:42.785895134Z" 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\"}" Nov 23 22:56:42.788695 containerd[1497]: time="2025-11-23T22:56:42.787867263Z" 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\"}" Nov 23 22:56:42.788851 containerd[1497]: time="2025-11-23T22:56:42.788750659Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Nov 23 22:56:42.789803 containerd[1497]: time="2025-11-23T22:56:42.789769829Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Nov 23 22:56:42.792757 containerd[1497]: time="2025-11-23T22:56:42.792711572Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 23 22:56:42.794840 containerd[1497]: time="2025-11-23T22:56:42.794795707Z" 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\"}" Nov 23 22:56:42.795901 containerd[1497]: time="2025-11-23T22:56:42.795866454Z" 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 528.404734ms" Nov 23 22:56:42.797405 containerd[1497]: time="2025-11-23T22:56:42.797367762Z" 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 545.487644ms" Nov 23 22:56:42.822706 containerd[1497]: time="2025-11-23T22:56:42.822432644Z" level=info msg="connecting to shim 51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8" address="unix:///run/containerd/s/b3938f276a684ceb2e3d7c9598998146f1403f6c337ebbeaedc17df2385d5035" namespace=k8s.io protocol=ttrpc version=3 Nov 23 22:56:42.824680 containerd[1497]: time="2025-11-23T22:56:42.824556230Z" level=info msg="connecting to shim 4eab789fa45d65602c9d206fe3ecb0fcb3dab9a892ed77a3a8bcb6d886b02522" address="unix:///run/containerd/s/48b417778cb8e5917b70acf8f63c4dbf9fc5d055b04291b97e672f55f86b4650" namespace=k8s.io protocol=ttrpc version=3 Nov 23 22:56:42.856989 systemd[1]: Started cri-containerd-4eab789fa45d65602c9d206fe3ecb0fcb3dab9a892ed77a3a8bcb6d886b02522.scope - libcontainer container 4eab789fa45d65602c9d206fe3ecb0fcb3dab9a892ed77a3a8bcb6d886b02522. Nov 23 22:56:42.860424 systemd[1]: Started cri-containerd-51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8.scope - libcontainer container 51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8. Nov 23 22:56:42.887380 containerd[1497]: time="2025-11-23T22:56:42.887338100Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-8vss5,Uid:f4c349f9-15c2-48b1-b084-41eab341003d,Namespace:kube-system,Attempt:0,} returns sandbox id \"4eab789fa45d65602c9d206fe3ecb0fcb3dab9a892ed77a3a8bcb6d886b02522\"" Nov 23 22:56:42.891274 containerd[1497]: time="2025-11-23T22:56:42.891233490Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.10\"" Nov 23 22:56:42.900839 containerd[1497]: time="2025-11-23T22:56:42.900792437Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-96mdd,Uid:b677327f-8583-4f54-980e-af36bf9f6beb,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8\"" Nov 23 22:56:42.920605 kubelet[1784]: E1123 22:56:42.920550 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:43.903785 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount575763112.mount: Deactivated successfully. Nov 23 22:56:43.920878 kubelet[1784]: E1123 22:56:43.920830 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:44.160118 containerd[1497]: time="2025-11-23T22:56:44.159953814Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:44.160715 containerd[1497]: time="2025-11-23T22:56:44.160677153Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.10: active requests=0, bytes read=27561801" Nov 23 22:56:44.161822 containerd[1497]: time="2025-11-23T22:56:44.161778109Z" level=info msg="ImageCreate event name:\"sha256:8b57c1f8bd2ddfa793889457b41e87132f192046e262b32ab0514f32d28be47d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:44.163986 containerd[1497]: time="2025-11-23T22:56:44.163937764Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:e3dda1c7b384f9eb5b2fa1c27493b23b80e6204b9fa2ee8791b2de078f468cbf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:44.164627 containerd[1497]: time="2025-11-23T22:56:44.164586265Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.10\" with image id \"sha256:8b57c1f8bd2ddfa793889457b41e87132f192046e262b32ab0514f32d28be47d\", repo tag \"registry.k8s.io/kube-proxy:v1.32.10\", repo digest \"registry.k8s.io/kube-proxy@sha256:e3dda1c7b384f9eb5b2fa1c27493b23b80e6204b9fa2ee8791b2de078f468cbf\", size \"27560818\" in 1.273308097s" Nov 23 22:56:44.164678 containerd[1497]: time="2025-11-23T22:56:44.164632313Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.10\" returns image reference \"sha256:8b57c1f8bd2ddfa793889457b41e87132f192046e262b32ab0514f32d28be47d\"" Nov 23 22:56:44.166086 containerd[1497]: time="2025-11-23T22:56:44.165770728Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 23 22:56:44.167244 containerd[1497]: time="2025-11-23T22:56:44.167199625Z" level=info msg="CreateContainer within sandbox \"4eab789fa45d65602c9d206fe3ecb0fcb3dab9a892ed77a3a8bcb6d886b02522\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 23 22:56:44.180056 containerd[1497]: time="2025-11-23T22:56:44.179997635Z" level=info msg="Container 02644458976920f0dbe522fff292165d48b6227a5560389e00c7077818260481: CDI devices from CRI Config.CDIDevices: []" Nov 23 22:56:44.188361 containerd[1497]: time="2025-11-23T22:56:44.188293757Z" level=info msg="CreateContainer within sandbox \"4eab789fa45d65602c9d206fe3ecb0fcb3dab9a892ed77a3a8bcb6d886b02522\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"02644458976920f0dbe522fff292165d48b6227a5560389e00c7077818260481\"" Nov 23 22:56:44.190373 containerd[1497]: time="2025-11-23T22:56:44.190346276Z" level=info msg="StartContainer for \"02644458976920f0dbe522fff292165d48b6227a5560389e00c7077818260481\"" Nov 23 22:56:44.191983 containerd[1497]: time="2025-11-23T22:56:44.191951299Z" level=info msg="connecting to shim 02644458976920f0dbe522fff292165d48b6227a5560389e00c7077818260481" address="unix:///run/containerd/s/48b417778cb8e5917b70acf8f63c4dbf9fc5d055b04291b97e672f55f86b4650" protocol=ttrpc version=3 Nov 23 22:56:44.217909 systemd[1]: Started cri-containerd-02644458976920f0dbe522fff292165d48b6227a5560389e00c7077818260481.scope - libcontainer container 02644458976920f0dbe522fff292165d48b6227a5560389e00c7077818260481. Nov 23 22:56:44.316401 containerd[1497]: time="2025-11-23T22:56:44.316309947Z" level=info msg="StartContainer for \"02644458976920f0dbe522fff292165d48b6227a5560389e00c7077818260481\" returns successfully" Nov 23 22:56:44.921540 kubelet[1784]: E1123 22:56:44.921458 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:45.231852 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4286904963.mount: Deactivated successfully. Nov 23 22:56:45.264989 containerd[1497]: time="2025-11-23T22:56:45.264920034Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:45.265506 containerd[1497]: time="2025-11-23T22:56:45.265472298Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Nov 23 22:56:45.266867 containerd[1497]: time="2025-11-23T22:56:45.266818318Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:45.270112 containerd[1497]: time="2025-11-23T22:56:45.270056579Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:45.271135 containerd[1497]: time="2025-11-23T22:56:45.271086627Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.105276757s" Nov 23 22:56:45.271227 containerd[1497]: time="2025-11-23T22:56:45.271138510Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 23 22:56:45.273458 containerd[1497]: time="2025-11-23T22:56:45.273414557Z" level=info msg="CreateContainer within sandbox \"51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 23 22:56:45.284544 containerd[1497]: time="2025-11-23T22:56:45.283884786Z" level=info msg="Container e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848: CDI devices from CRI Config.CDIDevices: []" Nov 23 22:56:45.290253 containerd[1497]: time="2025-11-23T22:56:45.290204410Z" level=info msg="CreateContainer within sandbox \"51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848\"" Nov 23 22:56:45.291255 containerd[1497]: time="2025-11-23T22:56:45.291222131Z" level=info msg="StartContainer for \"e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848\"" Nov 23 22:56:45.292647 containerd[1497]: time="2025-11-23T22:56:45.292613347Z" level=info msg="connecting to shim e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848" address="unix:///run/containerd/s/b3938f276a684ceb2e3d7c9598998146f1403f6c337ebbeaedc17df2385d5035" protocol=ttrpc version=3 Nov 23 22:56:45.318909 systemd[1]: Started cri-containerd-e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848.scope - libcontainer container e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848. Nov 23 22:56:45.348354 systemd[1]: cri-containerd-e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848.scope: Deactivated successfully. Nov 23 22:56:45.350891 containerd[1497]: time="2025-11-23T22:56:45.350742445Z" level=info msg="StartContainer for \"e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848\" returns successfully" Nov 23 22:56:45.352837 containerd[1497]: time="2025-11-23T22:56:45.352794927Z" level=info msg="received container exit event container_id:\"e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848\" id:\"e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848\" pid:2117 exited_at:{seconds:1763938605 nanos:352252572}" Nov 23 22:56:45.922476 kubelet[1784]: E1123 22:56:45.922425 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:46.107528 containerd[1497]: time="2025-11-23T22:56:46.107465398Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 23 22:56:46.125431 kubelet[1784]: I1123 22:56:46.125342 1784 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-8vss5" podStartSLOduration=4.849757413 podStartE2EDuration="6.125322192s" podCreationTimestamp="2025-11-23 22:56:40 +0000 UTC" firstStartedPulling="2025-11-23 22:56:42.890038935 +0000 UTC m=+3.908331423" lastFinishedPulling="2025-11-23 22:56:44.165603714 +0000 UTC m=+5.183896202" observedRunningTime="2025-11-23 22:56:45.11780245 +0000 UTC m=+6.136094938" watchObservedRunningTime="2025-11-23 22:56:46.125322192 +0000 UTC m=+7.143614681" Nov 23 22:56:46.234222 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e1c29849e6afd9505746259f612b5663550dee56c54c0fb224bbfad205576848-rootfs.mount: Deactivated successfully. Nov 23 22:56:46.923021 kubelet[1784]: E1123 22:56:46.922714 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:47.275969 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2429033064.mount: Deactivated successfully. Nov 23 22:56:47.923332 kubelet[1784]: E1123 22:56:47.923288 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:48.045366 containerd[1497]: time="2025-11-23T22:56:48.044834827Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:48.045366 containerd[1497]: time="2025-11-23T22:56:48.045311708Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874260" Nov 23 22:56:48.048306 containerd[1497]: time="2025-11-23T22:56:48.048244046Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:48.053365 containerd[1497]: time="2025-11-23T22:56:48.053318852Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:48.054199 containerd[1497]: time="2025-11-23T22:56:48.054170333Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.946656452s" Nov 23 22:56:48.054266 containerd[1497]: time="2025-11-23T22:56:48.054204293Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 23 22:56:48.057160 containerd[1497]: time="2025-11-23T22:56:48.056586768Z" level=info msg="CreateContainer within sandbox \"51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 23 22:56:48.067786 containerd[1497]: time="2025-11-23T22:56:48.066875915Z" level=info msg="Container a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271: CDI devices from CRI Config.CDIDevices: []" Nov 23 22:56:48.074731 containerd[1497]: time="2025-11-23T22:56:48.074689912Z" level=info msg="CreateContainer within sandbox \"51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271\"" Nov 23 22:56:48.075150 containerd[1497]: time="2025-11-23T22:56:48.075126644Z" level=info msg="StartContainer for \"a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271\"" Nov 23 22:56:48.076160 containerd[1497]: time="2025-11-23T22:56:48.076109140Z" level=info msg="connecting to shim a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271" address="unix:///run/containerd/s/b3938f276a684ceb2e3d7c9598998146f1403f6c337ebbeaedc17df2385d5035" protocol=ttrpc version=3 Nov 23 22:56:48.109897 systemd[1]: Started cri-containerd-a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271.scope - libcontainer container a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271. Nov 23 22:56:48.137074 systemd[1]: cri-containerd-a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271.scope: Deactivated successfully. Nov 23 22:56:48.159518 containerd[1497]: time="2025-11-23T22:56:48.159464214Z" level=info msg="received container exit event container_id:\"a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271\" id:\"a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271\" pid:2191 exited_at:{seconds:1763938608 nanos:137969694}" Nov 23 22:56:48.161218 containerd[1497]: time="2025-11-23T22:56:48.161176740Z" level=info msg="StartContainer for \"a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271\" returns successfully" Nov 23 22:56:48.177767 kubelet[1784]: I1123 22:56:48.177641 1784 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 23 22:56:48.182249 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a3f24b1b757ef2c4da689d94596f4776a33f5cf8eabfa7247412a9ffe8611271-rootfs.mount: Deactivated successfully. Nov 23 22:56:48.924439 kubelet[1784]: E1123 22:56:48.924363 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:49.122814 containerd[1497]: time="2025-11-23T22:56:49.122585398Z" level=info msg="CreateContainer within sandbox \"51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 23 22:56:49.132105 containerd[1497]: time="2025-11-23T22:56:49.132024188Z" level=info msg="Container 18925cf358b022217e7ed60e5ff5c3b93cba046b69faa425948ebcd6ca5403cd: CDI devices from CRI Config.CDIDevices: []" Nov 23 22:56:49.144205 containerd[1497]: time="2025-11-23T22:56:49.144142562Z" level=info msg="CreateContainer within sandbox \"51efffa8b4d0d05069bc7513d926d2fd6b7593273d2b1e47eee8eb3593fcd8f8\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"18925cf358b022217e7ed60e5ff5c3b93cba046b69faa425948ebcd6ca5403cd\"" Nov 23 22:56:49.144795 containerd[1497]: time="2025-11-23T22:56:49.144764401Z" level=info msg="StartContainer for \"18925cf358b022217e7ed60e5ff5c3b93cba046b69faa425948ebcd6ca5403cd\"" Nov 23 22:56:49.145755 containerd[1497]: time="2025-11-23T22:56:49.145725876Z" level=info msg="connecting to shim 18925cf358b022217e7ed60e5ff5c3b93cba046b69faa425948ebcd6ca5403cd" address="unix:///run/containerd/s/b3938f276a684ceb2e3d7c9598998146f1403f6c337ebbeaedc17df2385d5035" protocol=ttrpc version=3 Nov 23 22:56:49.180914 systemd[1]: Started cri-containerd-18925cf358b022217e7ed60e5ff5c3b93cba046b69faa425948ebcd6ca5403cd.scope - libcontainer container 18925cf358b022217e7ed60e5ff5c3b93cba046b69faa425948ebcd6ca5403cd. Nov 23 22:56:49.215394 containerd[1497]: time="2025-11-23T22:56:49.215349903Z" level=info msg="StartContainer for \"18925cf358b022217e7ed60e5ff5c3b93cba046b69faa425948ebcd6ca5403cd\" returns successfully" Nov 23 22:56:49.924766 kubelet[1784]: E1123 22:56:49.924696 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:50.282128 systemd-networkd[1418]: flannel.1: Link UP Nov 23 22:56:50.282137 systemd-networkd[1418]: flannel.1: Gained carrier Nov 23 22:56:50.925036 kubelet[1784]: E1123 22:56:50.924961 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:51.698504 kubelet[1784]: I1123 22:56:51.698396 1784 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-96mdd" podStartSLOduration=6.54557744 podStartE2EDuration="11.698373699s" podCreationTimestamp="2025-11-23 22:56:40 +0000 UTC" firstStartedPulling="2025-11-23 22:56:42.902463553 +0000 UTC m=+3.920756042" lastFinishedPulling="2025-11-23 22:56:48.055259812 +0000 UTC m=+9.073552301" observedRunningTime="2025-11-23 22:56:50.134573222 +0000 UTC m=+11.152865710" watchObservedRunningTime="2025-11-23 22:56:51.698373699 +0000 UTC m=+12.716666147" Nov 23 22:56:51.714381 systemd[1]: Created slice kubepods-besteffort-podddccd212_74e6_40bb_83e0_b25113122ba6.slice - libcontainer container kubepods-besteffort-podddccd212_74e6_40bb_83e0_b25113122ba6.slice. Nov 23 22:56:51.789009 systemd-networkd[1418]: flannel.1: Gained IPv6LL Nov 23 22:56:51.806681 kubelet[1784]: I1123 22:56:51.806623 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-w25tq\" (UniqueName: \"kubernetes.io/projected/ddccd212-74e6-40bb-83e0-b25113122ba6-kube-api-access-w25tq\") pod \"nginx-deployment-7fcdb87857-b5kbp\" (UID: \"ddccd212-74e6-40bb-83e0-b25113122ba6\") " pod="default/nginx-deployment-7fcdb87857-b5kbp" Nov 23 22:56:51.925555 kubelet[1784]: E1123 22:56:51.925497 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:52.019069 containerd[1497]: time="2025-11-23T22:56:52.018971838Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-b5kbp,Uid:ddccd212-74e6-40bb-83e0-b25113122ba6,Namespace:default,Attempt:0,}" Nov 23 22:56:52.035096 systemd-networkd[1418]: cni0: Link UP Nov 23 22:56:52.035104 systemd-networkd[1418]: cni0: Gained carrier Nov 23 22:56:52.035300 systemd-networkd[1418]: cni0: Lost carrier Nov 23 22:56:52.045709 systemd-networkd[1418]: vethbb4b34fc: Link UP Nov 23 22:56:52.048321 kernel: cni0: port 1(vethbb4b34fc) entered blocking state Nov 23 22:56:52.048649 kernel: cni0: port 1(vethbb4b34fc) entered disabled state Nov 23 22:56:52.049955 kernel: vethbb4b34fc: entered allmulticast mode Nov 23 22:56:52.050018 kernel: vethbb4b34fc: entered promiscuous mode Nov 23 22:56:52.058689 kernel: cni0: port 1(vethbb4b34fc) entered blocking state Nov 23 22:56:52.058781 kernel: cni0: port 1(vethbb4b34fc) entered forwarding state Nov 23 22:56:52.058738 systemd-networkd[1418]: vethbb4b34fc: Gained carrier Nov 23 22:56:52.059209 systemd-networkd[1418]: cni0: Gained carrier Nov 23 22:56:52.061019 containerd[1497]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000928e8), "name":"cbr0", "type":"bridge"} Nov 23 22:56:52.061019 containerd[1497]: delegateAdd: netconf sent to delegate plugin: Nov 23 22:56:52.090888 containerd[1497]: {"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-11-23T22:56:52.090832876Z" level=info msg="connecting to shim f6bf58c0749c505a36286e1f66e90896e34dd1bb8cc68ec17d728c6ed9711181" address="unix:///run/containerd/s/6c5e677f15069d887e5198c63ff9a20d0aa6711a0e6f4b0da4126dbad26437f9" namespace=k8s.io protocol=ttrpc version=3 Nov 23 22:56:52.127900 systemd[1]: Started cri-containerd-f6bf58c0749c505a36286e1f66e90896e34dd1bb8cc68ec17d728c6ed9711181.scope - libcontainer container f6bf58c0749c505a36286e1f66e90896e34dd1bb8cc68ec17d728c6ed9711181. Nov 23 22:56:52.141593 systemd-resolved[1421]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 23 22:56:52.167324 containerd[1497]: time="2025-11-23T22:56:52.167252976Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-b5kbp,Uid:ddccd212-74e6-40bb-83e0-b25113122ba6,Namespace:default,Attempt:0,} returns sandbox id \"f6bf58c0749c505a36286e1f66e90896e34dd1bb8cc68ec17d728c6ed9711181\"" Nov 23 22:56:52.168984 containerd[1497]: time="2025-11-23T22:56:52.168925592Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 23 22:56:52.925919 kubelet[1784]: E1123 22:56:52.925870 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:53.133170 systemd-networkd[1418]: cni0: Gained IPv6LL Nov 23 22:56:53.196818 systemd-networkd[1418]: vethbb4b34fc: Gained IPv6LL Nov 23 22:56:53.923739 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2790160934.mount: Deactivated successfully. Nov 23 22:56:53.927007 kubelet[1784]: E1123 22:56:53.926958 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:54.717173 containerd[1497]: time="2025-11-23T22:56:54.717100392Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:54.718711 containerd[1497]: time="2025-11-23T22:56:54.718671153Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017928" Nov 23 22:56:54.720553 containerd[1497]: time="2025-11-23T22:56:54.720517257Z" level=info msg="ImageCreate event name:\"sha256:565c017e0a8d632c6140fd58f27b8ab3e5911b0634e20dfbc186ba003edecf64\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:54.725705 containerd[1497]: time="2025-11-23T22:56:54.725629986Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:565c017e0a8d632c6140fd58f27b8ab3e5911b0634e20dfbc186ba003edecf64\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:496d316ce5372257a96d0a8d0c61aabe1665f6760bb61fc1cddd40aa5a6b92da\", size \"70017806\" in 2.556647404s" Nov 23 22:56:54.725791 containerd[1497]: time="2025-11-23T22:56:54.725711238Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:565c017e0a8d632c6140fd58f27b8ab3e5911b0634e20dfbc186ba003edecf64\"" Nov 23 22:56:54.726492 containerd[1497]: time="2025-11-23T22:56:54.726448958Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:496d316ce5372257a96d0a8d0c61aabe1665f6760bb61fc1cddd40aa5a6b92da\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:56:54.729255 containerd[1497]: time="2025-11-23T22:56:54.729212882Z" level=info msg="CreateContainer within sandbox \"f6bf58c0749c505a36286e1f66e90896e34dd1bb8cc68ec17d728c6ed9711181\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 23 22:56:54.739109 containerd[1497]: time="2025-11-23T22:56:54.739048397Z" level=info msg="Container b2dd49fc9c3f0a8e2e4cb84a4dd038884c1b550fde3d8d0e5303cef2fbb4d47c: CDI devices from CRI Config.CDIDevices: []" Nov 23 22:56:54.745615 containerd[1497]: time="2025-11-23T22:56:54.745543690Z" level=info msg="CreateContainer within sandbox \"f6bf58c0749c505a36286e1f66e90896e34dd1bb8cc68ec17d728c6ed9711181\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"b2dd49fc9c3f0a8e2e4cb84a4dd038884c1b550fde3d8d0e5303cef2fbb4d47c\"" Nov 23 22:56:54.746668 containerd[1497]: time="2025-11-23T22:56:54.746625337Z" level=info msg="StartContainer for \"b2dd49fc9c3f0a8e2e4cb84a4dd038884c1b550fde3d8d0e5303cef2fbb4d47c\"" Nov 23 22:56:54.747563 containerd[1497]: time="2025-11-23T22:56:54.747509687Z" level=info msg="connecting to shim b2dd49fc9c3f0a8e2e4cb84a4dd038884c1b550fde3d8d0e5303cef2fbb4d47c" address="unix:///run/containerd/s/6c5e677f15069d887e5198c63ff9a20d0aa6711a0e6f4b0da4126dbad26437f9" protocol=ttrpc version=3 Nov 23 22:56:54.772904 systemd[1]: Started cri-containerd-b2dd49fc9c3f0a8e2e4cb84a4dd038884c1b550fde3d8d0e5303cef2fbb4d47c.scope - libcontainer container b2dd49fc9c3f0a8e2e4cb84a4dd038884c1b550fde3d8d0e5303cef2fbb4d47c. Nov 23 22:56:54.813916 containerd[1497]: time="2025-11-23T22:56:54.813651931Z" level=info msg="StartContainer for \"b2dd49fc9c3f0a8e2e4cb84a4dd038884c1b550fde3d8d0e5303cef2fbb4d47c\" returns successfully" Nov 23 22:56:54.927835 kubelet[1784]: E1123 22:56:54.927778 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:55.147060 kubelet[1784]: I1123 22:56:55.146874 1784 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-b5kbp" podStartSLOduration=1.5886479740000001 podStartE2EDuration="4.146857015s" podCreationTimestamp="2025-11-23 22:56:51 +0000 UTC" firstStartedPulling="2025-11-23 22:56:52.168266493 +0000 UTC m=+13.186558981" lastFinishedPulling="2025-11-23 22:56:54.726475574 +0000 UTC m=+15.744768022" observedRunningTime="2025-11-23 22:56:55.146828242 +0000 UTC m=+16.165120730" watchObservedRunningTime="2025-11-23 22:56:55.146857015 +0000 UTC m=+16.165149503" Nov 23 22:56:55.928831 kubelet[1784]: E1123 22:56:55.928429 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:56.928949 kubelet[1784]: E1123 22:56:56.928882 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:57.929131 kubelet[1784]: E1123 22:56:57.929055 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:58.088408 systemd[1]: Created slice kubepods-besteffort-pod1f149bea_a711_4bad_90d2_f7993b056962.slice - libcontainer container kubepods-besteffort-pod1f149bea_a711_4bad_90d2_f7993b056962.slice. Nov 23 22:56:58.144359 kubelet[1784]: I1123 22:56:58.144281 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dsfjr\" (UniqueName: \"kubernetes.io/projected/1f149bea-a711-4bad-90d2-f7993b056962-kube-api-access-dsfjr\") pod \"nfs-server-provisioner-0\" (UID: \"1f149bea-a711-4bad-90d2-f7993b056962\") " pod="default/nfs-server-provisioner-0" Nov 23 22:56:58.144359 kubelet[1784]: I1123 22:56:58.144329 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1f149bea-a711-4bad-90d2-f7993b056962-data\") pod \"nfs-server-provisioner-0\" (UID: \"1f149bea-a711-4bad-90d2-f7993b056962\") " pod="default/nfs-server-provisioner-0" Nov 23 22:56:58.393224 containerd[1497]: time="2025-11-23T22:56:58.392796193Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1f149bea-a711-4bad-90d2-f7993b056962,Namespace:default,Attempt:0,}" Nov 23 22:56:58.426538 systemd-networkd[1418]: vethb70417f5: Link UP Nov 23 22:56:58.429974 kernel: cni0: port 2(vethb70417f5) entered blocking state Nov 23 22:56:58.430068 kernel: cni0: port 2(vethb70417f5) entered disabled state Nov 23 22:56:58.430085 kernel: vethb70417f5: entered allmulticast mode Nov 23 22:56:58.430840 kernel: vethb70417f5: entered promiscuous mode Nov 23 22:56:58.436944 kernel: cni0: port 2(vethb70417f5) entered blocking state Nov 23 22:56:58.437040 kernel: cni0: port 2(vethb70417f5) entered forwarding state Nov 23 22:56:58.437159 systemd-networkd[1418]: vethb70417f5: Gained carrier Nov 23 22:56:58.441411 containerd[1497]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001a938), "name":"cbr0", "type":"bridge"} Nov 23 22:56:58.441411 containerd[1497]: delegateAdd: netconf sent to delegate plugin: Nov 23 22:56:58.475068 containerd[1497]: {"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-11-23T22:56:58.474990471Z" level=info msg="connecting to shim d539e11160b00d48d89ff1f412a0759e058575a97a1dd7284b7ce38bd82e5e52" address="unix:///run/containerd/s/f71816e384d2542f27550609c5c8d0757a7a2b53b3860b578ea01e0107b91bbd" namespace=k8s.io protocol=ttrpc version=3 Nov 23 22:56:58.505889 systemd[1]: Started cri-containerd-d539e11160b00d48d89ff1f412a0759e058575a97a1dd7284b7ce38bd82e5e52.scope - libcontainer container d539e11160b00d48d89ff1f412a0759e058575a97a1dd7284b7ce38bd82e5e52. Nov 23 22:56:58.518035 systemd-resolved[1421]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 23 22:56:58.540367 containerd[1497]: time="2025-11-23T22:56:58.540301246Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1f149bea-a711-4bad-90d2-f7993b056962,Namespace:default,Attempt:0,} returns sandbox id \"d539e11160b00d48d89ff1f412a0759e058575a97a1dd7284b7ce38bd82e5e52\"" Nov 23 22:56:58.542167 containerd[1497]: time="2025-11-23T22:56:58.542128112Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 23 22:56:58.930288 kubelet[1784]: E1123 22:56:58.930228 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:59.852832 systemd-networkd[1418]: vethb70417f5: Gained IPv6LL Nov 23 22:56:59.918794 kubelet[1784]: E1123 22:56:59.918737 1784 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:56:59.930409 kubelet[1784]: E1123 22:56:59.930371 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:00.338748 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3729625143.mount: Deactivated successfully. Nov 23 22:57:00.931349 kubelet[1784]: E1123 22:57:00.931303 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:01.838421 containerd[1497]: time="2025-11-23T22:57:01.837953119Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:57:01.838954 containerd[1497]: time="2025-11-23T22:57:01.838921924Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Nov 23 22:57:01.840526 containerd[1497]: time="2025-11-23T22:57:01.840461004Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:57:01.844214 containerd[1497]: time="2025-11-23T22:57:01.844155555Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:57:01.844864 containerd[1497]: time="2025-11-23T22:57:01.844729352Z" 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.302559793s" Nov 23 22:57:01.844864 containerd[1497]: time="2025-11-23T22:57:01.844776311Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 23 22:57:01.847789 containerd[1497]: time="2025-11-23T22:57:01.847754907Z" level=info msg="CreateContainer within sandbox \"d539e11160b00d48d89ff1f412a0759e058575a97a1dd7284b7ce38bd82e5e52\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 23 22:57:01.859241 containerd[1497]: time="2025-11-23T22:57:01.857872759Z" level=info msg="Container d8a3e4ec5a4a10f709fce34be2011ed27d48cae894f00d05dab8dc599accd532: CDI devices from CRI Config.CDIDevices: []" Nov 23 22:57:01.866895 containerd[1497]: time="2025-11-23T22:57:01.866851463Z" level=info msg="CreateContainer within sandbox \"d539e11160b00d48d89ff1f412a0759e058575a97a1dd7284b7ce38bd82e5e52\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"d8a3e4ec5a4a10f709fce34be2011ed27d48cae894f00d05dab8dc599accd532\"" Nov 23 22:57:01.867684 containerd[1497]: time="2025-11-23T22:57:01.867349877Z" level=info msg="StartContainer for \"d8a3e4ec5a4a10f709fce34be2011ed27d48cae894f00d05dab8dc599accd532\"" Nov 23 22:57:01.868497 containerd[1497]: time="2025-11-23T22:57:01.868467767Z" level=info msg="connecting to shim d8a3e4ec5a4a10f709fce34be2011ed27d48cae894f00d05dab8dc599accd532" address="unix:///run/containerd/s/f71816e384d2542f27550609c5c8d0757a7a2b53b3860b578ea01e0107b91bbd" protocol=ttrpc version=3 Nov 23 22:57:01.896916 systemd[1]: Started cri-containerd-d8a3e4ec5a4a10f709fce34be2011ed27d48cae894f00d05dab8dc599accd532.scope - libcontainer container d8a3e4ec5a4a10f709fce34be2011ed27d48cae894f00d05dab8dc599accd532. Nov 23 22:57:01.929070 containerd[1497]: time="2025-11-23T22:57:01.928966742Z" level=info msg="StartContainer for \"d8a3e4ec5a4a10f709fce34be2011ed27d48cae894f00d05dab8dc599accd532\" returns successfully" Nov 23 22:57:01.932071 kubelet[1784]: E1123 22:57:01.932014 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:02.932354 kubelet[1784]: E1123 22:57:02.932305 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:03.932847 kubelet[1784]: E1123 22:57:03.932789 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:04.933149 kubelet[1784]: E1123 22:57:04.933095 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:05.933875 kubelet[1784]: E1123 22:57:05.933808 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:06.935058 kubelet[1784]: E1123 22:57:06.934997 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:07.935534 kubelet[1784]: E1123 22:57:07.935433 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:08.936255 kubelet[1784]: E1123 22:57:08.936204 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:09.936853 kubelet[1784]: E1123 22:57:09.936804 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:10.937848 kubelet[1784]: E1123 22:57:10.937796 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:11.938191 kubelet[1784]: E1123 22:57:11.938143 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:12.032173 kubelet[1784]: I1123 22:57:12.032083 1784 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.727771062 podStartE2EDuration="14.03206252s" podCreationTimestamp="2025-11-23 22:56:58 +0000 UTC" firstStartedPulling="2025-11-23 22:56:58.541901711 +0000 UTC m=+19.560194199" lastFinishedPulling="2025-11-23 22:57:01.846193169 +0000 UTC m=+22.864485657" observedRunningTime="2025-11-23 22:57:02.163090685 +0000 UTC m=+23.181383133" watchObservedRunningTime="2025-11-23 22:57:12.03206252 +0000 UTC m=+33.050355008" Nov 23 22:57:12.041282 systemd[1]: Created slice kubepods-besteffort-pod72089781_2b84_4c32_abf5_fc45c022c9b6.slice - libcontainer container kubepods-besteffort-pod72089781_2b84_4c32_abf5_fc45c022c9b6.slice. Nov 23 22:57:12.137137 kubelet[1784]: I1123 22:57:12.137066 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tdx4d\" (UniqueName: \"kubernetes.io/projected/72089781-2b84-4c32-abf5-fc45c022c9b6-kube-api-access-tdx4d\") pod \"test-pod-1\" (UID: \"72089781-2b84-4c32-abf5-fc45c022c9b6\") " pod="default/test-pod-1" Nov 23 22:57:12.137137 kubelet[1784]: I1123 22:57:12.137115 1784 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-df3375f5-0f00-4715-a15c-073ca9fe0cbe\" (UniqueName: \"kubernetes.io/nfs/72089781-2b84-4c32-abf5-fc45c022c9b6-pvc-df3375f5-0f00-4715-a15c-073ca9fe0cbe\") pod \"test-pod-1\" (UID: \"72089781-2b84-4c32-abf5-fc45c022c9b6\") " pod="default/test-pod-1" Nov 23 22:57:12.275294 kernel: netfs: FS-Cache loaded Nov 23 22:57:12.302197 kernel: RPC: Registered named UNIX socket transport module. Nov 23 22:57:12.302323 kernel: RPC: Registered udp transport module. Nov 23 22:57:12.302342 kernel: RPC: Registered tcp transport module. Nov 23 22:57:12.302356 kernel: RPC: Registered tcp-with-tls transport module. Nov 23 22:57:12.304752 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 23 22:57:12.501758 kernel: NFS: Registering the id_resolver key type Nov 23 22:57:12.501870 kernel: Key type id_resolver registered Nov 23 22:57:12.501886 kernel: Key type id_legacy registered Nov 23 22:57:12.523781 nfsidmap[2799]: 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 Nov 23 22:57:12.525024 nfsidmap[2799]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 23 22:57:12.528327 nfsidmap[2802]: 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 Nov 23 22:57:12.528497 nfsidmap[2802]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 23 22:57:12.535326 nfsrahead[2806]: setting /var/lib/kubelet/pods/72089781-2b84-4c32-abf5-fc45c022c9b6/volumes/kubernetes.io~nfs/pvc-df3375f5-0f00-4715-a15c-073ca9fe0cbe readahead to 128 Nov 23 22:57:12.645520 containerd[1497]: time="2025-11-23T22:57:12.645411695Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:72089781-2b84-4c32-abf5-fc45c022c9b6,Namespace:default,Attempt:0,}" Nov 23 22:57:12.656066 systemd-networkd[1418]: vethe529c1fb: Link UP Nov 23 22:57:12.657749 kernel: cni0: port 3(vethe529c1fb) entered blocking state Nov 23 22:57:12.657836 kernel: cni0: port 3(vethe529c1fb) entered disabled state Nov 23 22:57:12.657854 kernel: vethe529c1fb: entered allmulticast mode Nov 23 22:57:12.659122 kernel: vethe529c1fb: entered promiscuous mode Nov 23 22:57:12.667285 kernel: cni0: port 3(vethe529c1fb) entered blocking state Nov 23 22:57:12.667371 kernel: cni0: port 3(vethe529c1fb) entered forwarding state Nov 23 22:57:12.667549 systemd-networkd[1418]: vethe529c1fb: Gained carrier Nov 23 22:57:12.669143 containerd[1497]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014938), "name":"cbr0", "type":"bridge"} Nov 23 22:57:12.669143 containerd[1497]: delegateAdd: netconf sent to delegate plugin: Nov 23 22:57:12.692188 containerd[1497]: {"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-11-23T22:57:12.692142727Z" level=info msg="connecting to shim 26f92e23c835bb630f382b46a05a928eff8e920236f1bee289e9f2e1cd06422f" address="unix:///run/containerd/s/d1a2f466bdce3f03065a64513eb1d3734d72e0d5b83c025e086e0581639960dc" namespace=k8s.io protocol=ttrpc version=3 Nov 23 22:57:12.717882 systemd[1]: Started cri-containerd-26f92e23c835bb630f382b46a05a928eff8e920236f1bee289e9f2e1cd06422f.scope - libcontainer container 26f92e23c835bb630f382b46a05a928eff8e920236f1bee289e9f2e1cd06422f. Nov 23 22:57:12.729551 systemd-resolved[1421]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 23 22:57:12.750752 containerd[1497]: time="2025-11-23T22:57:12.750671165Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:72089781-2b84-4c32-abf5-fc45c022c9b6,Namespace:default,Attempt:0,} returns sandbox id \"26f92e23c835bb630f382b46a05a928eff8e920236f1bee289e9f2e1cd06422f\"" Nov 23 22:57:12.752639 containerd[1497]: time="2025-11-23T22:57:12.752578165Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 23 22:57:12.938885 kubelet[1784]: E1123 22:57:12.938832 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:12.978738 containerd[1497]: time="2025-11-23T22:57:12.977945342Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 23 22:57:12.978908 containerd[1497]: time="2025-11-23T22:57:12.978877809Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 23 22:57:12.982015 containerd[1497]: time="2025-11-23T22:57:12.981943249Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:565c017e0a8d632c6140fd58f27b8ab3e5911b0634e20dfbc186ba003edecf64\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:496d316ce5372257a96d0a8d0c61aabe1665f6760bb61fc1cddd40aa5a6b92da\", size \"70017806\" in 229.328823ms" Nov 23 22:57:12.982015 containerd[1497]: time="2025-11-23T22:57:12.982000082Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:565c017e0a8d632c6140fd58f27b8ab3e5911b0634e20dfbc186ba003edecf64\"" Nov 23 22:57:12.984875 containerd[1497]: time="2025-11-23T22:57:12.984837348Z" level=info msg="CreateContainer within sandbox \"26f92e23c835bb630f382b46a05a928eff8e920236f1bee289e9f2e1cd06422f\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 23 22:57:12.993109 containerd[1497]: time="2025-11-23T22:57:12.993057653Z" level=info msg="Container b8e6a9ce071b4c5ecf0ce0a6de06084f3deff491856be6e66aef6682d944f031: CDI devices from CRI Config.CDIDevices: []" Nov 23 22:57:13.000274 containerd[1497]: time="2025-11-23T22:57:13.000209131Z" level=info msg="CreateContainer within sandbox \"26f92e23c835bb630f382b46a05a928eff8e920236f1bee289e9f2e1cd06422f\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"b8e6a9ce071b4c5ecf0ce0a6de06084f3deff491856be6e66aef6682d944f031\"" Nov 23 22:57:13.000761 containerd[1497]: time="2025-11-23T22:57:13.000714947Z" level=info msg="StartContainer for \"b8e6a9ce071b4c5ecf0ce0a6de06084f3deff491856be6e66aef6682d944f031\"" Nov 23 22:57:13.001697 containerd[1497]: time="2025-11-23T22:57:13.001623733Z" level=info msg="connecting to shim b8e6a9ce071b4c5ecf0ce0a6de06084f3deff491856be6e66aef6682d944f031" address="unix:///run/containerd/s/d1a2f466bdce3f03065a64513eb1d3734d72e0d5b83c025e086e0581639960dc" protocol=ttrpc version=3 Nov 23 22:57:13.025934 systemd[1]: Started cri-containerd-b8e6a9ce071b4c5ecf0ce0a6de06084f3deff491856be6e66aef6682d944f031.scope - libcontainer container b8e6a9ce071b4c5ecf0ce0a6de06084f3deff491856be6e66aef6682d944f031. Nov 23 22:57:13.061278 containerd[1497]: time="2025-11-23T22:57:13.061231778Z" level=info msg="StartContainer for \"b8e6a9ce071b4c5ecf0ce0a6de06084f3deff491856be6e66aef6682d944f031\" returns successfully" Nov 23 22:57:13.189864 kubelet[1784]: I1123 22:57:13.189628 1784 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.958808645 podStartE2EDuration="15.189607811s" podCreationTimestamp="2025-11-23 22:56:58 +0000 UTC" firstStartedPulling="2025-11-23 22:57:12.751962723 +0000 UTC m=+33.770255211" lastFinishedPulling="2025-11-23 22:57:12.982761889 +0000 UTC m=+34.001054377" observedRunningTime="2025-11-23 22:57:13.18951648 +0000 UTC m=+34.207808968" watchObservedRunningTime="2025-11-23 22:57:13.189607811 +0000 UTC m=+34.207900299" Nov 23 22:57:13.940105 kubelet[1784]: E1123 22:57:13.940036 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:14.188969 systemd-networkd[1418]: vethe529c1fb: Gained IPv6LL Nov 23 22:57:14.940531 kubelet[1784]: E1123 22:57:14.940482 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 23 22:57:15.421540 update_engine[1481]: I20251123 22:57:15.420985 1481 update_attempter.cc:509] Updating boot flags... Nov 23 22:57:15.940863 kubelet[1784]: E1123 22:57:15.940822 1784 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"