Nov 5 15:02:17.349418 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 5 15:02:17.349441 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Wed Nov 5 13:42:06 -00 2025 Nov 5 15:02:17.349449 kernel: KASLR enabled Nov 5 15:02:17.349455 kernel: efi: EFI v2.7 by EDK II Nov 5 15:02:17.349461 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Nov 5 15:02:17.349467 kernel: random: crng init done Nov 5 15:02:17.349474 kernel: secureboot: Secure boot disabled Nov 5 15:02:17.349480 kernel: ACPI: Early table checksum verification disabled Nov 5 15:02:17.349487 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 5 15:02:17.349493 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 5 15:02:17.349500 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349506 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349512 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349518 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349526 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349533 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349539 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349545 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349552 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:02:17.349558 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 5 15:02:17.349564 kernel: ACPI: Use ACPI SPCR as default console: No Nov 5 15:02:17.349571 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 15:02:17.349579 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Nov 5 15:02:17.349585 kernel: Zone ranges: Nov 5 15:02:17.349592 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 15:02:17.349598 kernel: DMA32 empty Nov 5 15:02:17.349604 kernel: Normal empty Nov 5 15:02:17.349610 kernel: Device empty Nov 5 15:02:17.349617 kernel: Movable zone start for each node Nov 5 15:02:17.349623 kernel: Early memory node ranges Nov 5 15:02:17.349629 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Nov 5 15:02:17.349636 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Nov 5 15:02:17.349642 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Nov 5 15:02:17.349648 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 5 15:02:17.349656 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 5 15:02:17.349662 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 5 15:02:17.349669 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 5 15:02:17.349675 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 5 15:02:17.349681 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 5 15:02:17.349688 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 5 15:02:17.349698 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 5 15:02:17.349704 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 5 15:02:17.349711 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 5 15:02:17.349718 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 15:02:17.349725 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Nov 5 15:02:17.349732 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Nov 5 15:02:17.349738 kernel: psci: probing for conduit method from ACPI. Nov 5 15:02:17.349745 kernel: psci: PSCIv1.1 detected in firmware. Nov 5 15:02:17.349753 kernel: psci: Using standard PSCI v0.2 function IDs Nov 5 15:02:17.349760 kernel: psci: Trusted OS migration not required Nov 5 15:02:17.349766 kernel: psci: SMC Calling Convention v1.1 Nov 5 15:02:17.349773 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Nov 5 15:02:17.349780 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Nov 5 15:02:17.349786 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Nov 5 15:02:17.349793 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 5 15:02:17.349800 kernel: Detected PIPT I-cache on CPU0 Nov 5 15:02:17.349807 kernel: CPU features: detected: GIC system register CPU interface Nov 5 15:02:17.349814 kernel: CPU features: detected: Spectre-v4 Nov 5 15:02:17.349821 kernel: CPU features: detected: Spectre-BHB Nov 5 15:02:17.349829 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 5 15:02:17.349836 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 5 15:02:17.349843 kernel: CPU features: detected: ARM erratum 1418040 Nov 5 15:02:17.349850 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 5 15:02:17.349857 kernel: alternatives: applying boot alternatives Nov 5 15:02:17.349865 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=15758474ef4cace68fb389c1b75e821ab8f30d9b752a28429e0459793723ea7b Nov 5 15:02:17.349872 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 5 15:02:17.349879 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 5 15:02:17.349886 kernel: Fallback order for Node 0: 0 Nov 5 15:02:17.349893 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Nov 5 15:02:17.349900 kernel: Policy zone: DMA Nov 5 15:02:17.349907 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 5 15:02:17.349914 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Nov 5 15:02:17.349921 kernel: software IO TLB: area num 4. Nov 5 15:02:17.349928 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Nov 5 15:02:17.349935 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Nov 5 15:02:17.349942 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 5 15:02:17.349948 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 5 15:02:17.349956 kernel: rcu: RCU event tracing is enabled. Nov 5 15:02:17.349963 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 5 15:02:17.349970 kernel: Trampoline variant of Tasks RCU enabled. Nov 5 15:02:17.349979 kernel: Tracing variant of Tasks RCU enabled. Nov 5 15:02:17.349986 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 5 15:02:17.349993 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 5 15:02:17.350000 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 5 15:02:17.350007 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 5 15:02:17.350013 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 5 15:02:17.350020 kernel: GICv3: 256 SPIs implemented Nov 5 15:02:17.350027 kernel: GICv3: 0 Extended SPIs implemented Nov 5 15:02:17.350034 kernel: Root IRQ handler: gic_handle_irq Nov 5 15:02:17.350040 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Nov 5 15:02:17.350047 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Nov 5 15:02:17.350055 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 5 15:02:17.350062 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 5 15:02:17.350069 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Nov 5 15:02:17.350076 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Nov 5 15:02:17.350083 kernel: GICv3: using LPI property table @0x0000000040130000 Nov 5 15:02:17.350089 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Nov 5 15:02:17.350096 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 5 15:02:17.350103 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:02:17.350110 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 5 15:02:17.350117 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 5 15:02:17.350124 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 5 15:02:17.350132 kernel: arm-pv: using stolen time PV Nov 5 15:02:17.350140 kernel: Console: colour dummy device 80x25 Nov 5 15:02:17.350147 kernel: ACPI: Core revision 20240827 Nov 5 15:02:17.350154 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 5 15:02:17.350162 kernel: pid_max: default: 32768 minimum: 301 Nov 5 15:02:17.350169 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 5 15:02:17.350176 kernel: landlock: Up and running. Nov 5 15:02:17.350183 kernel: SELinux: Initializing. Nov 5 15:02:17.350191 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:02:17.350199 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:02:17.350206 kernel: rcu: Hierarchical SRCU implementation. Nov 5 15:02:17.350213 kernel: rcu: Max phase no-delay instances is 400. Nov 5 15:02:17.350221 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 5 15:02:17.350228 kernel: Remapping and enabling EFI services. Nov 5 15:02:17.350235 kernel: smp: Bringing up secondary CPUs ... Nov 5 15:02:17.350250 kernel: Detected PIPT I-cache on CPU1 Nov 5 15:02:17.350263 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 5 15:02:17.350273 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Nov 5 15:02:17.350280 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:02:17.350288 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 5 15:02:17.350296 kernel: Detected PIPT I-cache on CPU2 Nov 5 15:02:17.350303 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 5 15:02:17.350324 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Nov 5 15:02:17.350332 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:02:17.350340 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 5 15:02:17.350347 kernel: Detected PIPT I-cache on CPU3 Nov 5 15:02:17.350355 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 5 15:02:17.350363 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Nov 5 15:02:17.350371 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:02:17.350380 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 5 15:02:17.350387 kernel: smp: Brought up 1 node, 4 CPUs Nov 5 15:02:17.350395 kernel: SMP: Total of 4 processors activated. Nov 5 15:02:17.350402 kernel: CPU: All CPU(s) started at EL1 Nov 5 15:02:17.350410 kernel: CPU features: detected: 32-bit EL0 Support Nov 5 15:02:17.350418 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 5 15:02:17.350425 kernel: CPU features: detected: Common not Private translations Nov 5 15:02:17.350435 kernel: CPU features: detected: CRC32 instructions Nov 5 15:02:17.350442 kernel: CPU features: detected: Enhanced Virtualization Traps Nov 5 15:02:17.350450 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 5 15:02:17.350457 kernel: CPU features: detected: LSE atomic instructions Nov 5 15:02:17.350465 kernel: CPU features: detected: Privileged Access Never Nov 5 15:02:17.350473 kernel: CPU features: detected: RAS Extension Support Nov 5 15:02:17.350480 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 5 15:02:17.350488 kernel: alternatives: applying system-wide alternatives Nov 5 15:02:17.350497 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Nov 5 15:02:17.350505 kernel: Memory: 2450400K/2572288K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 99552K reserved, 16384K cma-reserved) Nov 5 15:02:17.350512 kernel: devtmpfs: initialized Nov 5 15:02:17.350520 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 5 15:02:17.350528 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 5 15:02:17.350536 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 5 15:02:17.350543 kernel: 0 pages in range for non-PLT usage Nov 5 15:02:17.350552 kernel: 515056 pages in range for PLT usage Nov 5 15:02:17.350560 kernel: pinctrl core: initialized pinctrl subsystem Nov 5 15:02:17.350567 kernel: SMBIOS 3.0.0 present. Nov 5 15:02:17.350575 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 5 15:02:17.350583 kernel: DMI: Memory slots populated: 1/1 Nov 5 15:02:17.350590 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 5 15:02:17.350597 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 5 15:02:17.350606 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 5 15:02:17.350614 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 5 15:02:17.350622 kernel: audit: initializing netlink subsys (disabled) Nov 5 15:02:17.350630 kernel: audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 Nov 5 15:02:17.350637 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 5 15:02:17.350645 kernel: cpuidle: using governor menu Nov 5 15:02:17.350653 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 5 15:02:17.350662 kernel: ASID allocator initialised with 32768 entries Nov 5 15:02:17.350669 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 5 15:02:17.350677 kernel: Serial: AMBA PL011 UART driver Nov 5 15:02:17.350685 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 5 15:02:17.350693 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 5 15:02:17.350700 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 5 15:02:17.350708 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 5 15:02:17.350716 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 5 15:02:17.350725 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 5 15:02:17.350733 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 5 15:02:17.350740 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 5 15:02:17.350748 kernel: ACPI: Added _OSI(Module Device) Nov 5 15:02:17.350756 kernel: ACPI: Added _OSI(Processor Device) Nov 5 15:02:17.350763 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 5 15:02:17.350771 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 5 15:02:17.350779 kernel: ACPI: Interpreter enabled Nov 5 15:02:17.350787 kernel: ACPI: Using GIC for interrupt routing Nov 5 15:02:17.350795 kernel: ACPI: MCFG table detected, 1 entries Nov 5 15:02:17.350802 kernel: ACPI: CPU0 has been hot-added Nov 5 15:02:17.350810 kernel: ACPI: CPU1 has been hot-added Nov 5 15:02:17.350817 kernel: ACPI: CPU2 has been hot-added Nov 5 15:02:17.350825 kernel: ACPI: CPU3 has been hot-added Nov 5 15:02:17.350834 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 5 15:02:17.350842 kernel: printk: legacy console [ttyAMA0] enabled Nov 5 15:02:17.350849 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 5 15:02:17.351008 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 5 15:02:17.351093 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 5 15:02:17.351171 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 5 15:02:17.351261 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 5 15:02:17.351352 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 5 15:02:17.351362 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 5 15:02:17.351370 kernel: PCI host bridge to bus 0000:00 Nov 5 15:02:17.351457 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 5 15:02:17.351528 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 5 15:02:17.351601 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 5 15:02:17.351673 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 5 15:02:17.351765 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Nov 5 15:02:17.351856 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Nov 5 15:02:17.351942 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Nov 5 15:02:17.352022 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Nov 5 15:02:17.352103 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Nov 5 15:02:17.352182 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Nov 5 15:02:17.352275 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Nov 5 15:02:17.352368 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Nov 5 15:02:17.352441 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 5 15:02:17.352515 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 5 15:02:17.352590 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 5 15:02:17.352599 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 5 15:02:17.352607 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 5 15:02:17.352615 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 5 15:02:17.352623 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 5 15:02:17.352630 kernel: iommu: Default domain type: Translated Nov 5 15:02:17.352639 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 5 15:02:17.352647 kernel: efivars: Registered efivars operations Nov 5 15:02:17.352654 kernel: vgaarb: loaded Nov 5 15:02:17.352661 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 5 15:02:17.352669 kernel: VFS: Disk quotas dquot_6.6.0 Nov 5 15:02:17.352676 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 5 15:02:17.352684 kernel: pnp: PnP ACPI init Nov 5 15:02:17.352777 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 5 15:02:17.352788 kernel: pnp: PnP ACPI: found 1 devices Nov 5 15:02:17.352795 kernel: NET: Registered PF_INET protocol family Nov 5 15:02:17.352803 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 5 15:02:17.352811 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 5 15:02:17.352818 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 5 15:02:17.352826 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 5 15:02:17.352835 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 5 15:02:17.352843 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 5 15:02:17.352851 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:02:17.352858 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:02:17.352866 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 5 15:02:17.352873 kernel: PCI: CLS 0 bytes, default 64 Nov 5 15:02:17.352881 kernel: kvm [1]: HYP mode not available Nov 5 15:02:17.352890 kernel: Initialise system trusted keyrings Nov 5 15:02:17.352897 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 5 15:02:17.352905 kernel: Key type asymmetric registered Nov 5 15:02:17.352912 kernel: Asymmetric key parser 'x509' registered Nov 5 15:02:17.352920 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Nov 5 15:02:17.352927 kernel: io scheduler mq-deadline registered Nov 5 15:02:17.352934 kernel: io scheduler kyber registered Nov 5 15:02:17.352943 kernel: io scheduler bfq registered Nov 5 15:02:17.352951 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 5 15:02:17.352958 kernel: ACPI: button: Power Button [PWRB] Nov 5 15:02:17.352967 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 5 15:02:17.353045 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 5 15:02:17.353055 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 5 15:02:17.353063 kernel: thunder_xcv, ver 1.0 Nov 5 15:02:17.353072 kernel: thunder_bgx, ver 1.0 Nov 5 15:02:17.353079 kernel: nicpf, ver 1.0 Nov 5 15:02:17.353087 kernel: nicvf, ver 1.0 Nov 5 15:02:17.353173 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 5 15:02:17.353257 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-05T15:02:16 UTC (1762354936) Nov 5 15:02:17.353268 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 5 15:02:17.353278 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Nov 5 15:02:17.353285 kernel: watchdog: NMI not fully supported Nov 5 15:02:17.353293 kernel: watchdog: Hard watchdog permanently disabled Nov 5 15:02:17.353301 kernel: NET: Registered PF_INET6 protocol family Nov 5 15:02:17.353309 kernel: Segment Routing with IPv6 Nov 5 15:02:17.353324 kernel: In-situ OAM (IOAM) with IPv6 Nov 5 15:02:17.353346 kernel: NET: Registered PF_PACKET protocol family Nov 5 15:02:17.353353 kernel: Key type dns_resolver registered Nov 5 15:02:17.353363 kernel: registered taskstats version 1 Nov 5 15:02:17.353371 kernel: Loading compiled-in X.509 certificates Nov 5 15:02:17.353378 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 4b3babb46eb583bd8b0310732885d24e60ea58c5' Nov 5 15:02:17.353386 kernel: Demotion targets for Node 0: null Nov 5 15:02:17.353393 kernel: Key type .fscrypt registered Nov 5 15:02:17.353401 kernel: Key type fscrypt-provisioning registered Nov 5 15:02:17.353408 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 5 15:02:17.353417 kernel: ima: Allocated hash algorithm: sha1 Nov 5 15:02:17.353425 kernel: ima: No architecture policies found Nov 5 15:02:17.353432 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 5 15:02:17.353440 kernel: clk: Disabling unused clocks Nov 5 15:02:17.353447 kernel: PM: genpd: Disabling unused power domains Nov 5 15:02:17.353454 kernel: Freeing unused kernel memory: 12992K Nov 5 15:02:17.353462 kernel: Run /init as init process Nov 5 15:02:17.353470 kernel: with arguments: Nov 5 15:02:17.353478 kernel: /init Nov 5 15:02:17.353485 kernel: with environment: Nov 5 15:02:17.353493 kernel: HOME=/ Nov 5 15:02:17.353500 kernel: TERM=linux Nov 5 15:02:17.353600 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Nov 5 15:02:17.353677 kernel: virtio_blk virtio1: [vda] 27000832 512-byte logical blocks (13.8 GB/12.9 GiB) Nov 5 15:02:17.353689 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 5 15:02:17.353697 kernel: GPT:16515071 != 27000831 Nov 5 15:02:17.353705 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 5 15:02:17.353712 kernel: GPT:16515071 != 27000831 Nov 5 15:02:17.353720 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 5 15:02:17.353727 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 15:02:17.353736 kernel: SCSI subsystem initialized Nov 5 15:02:17.353744 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 5 15:02:17.353751 kernel: device-mapper: uevent: version 1.0.3 Nov 5 15:02:17.353759 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 5 15:02:17.353767 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Nov 5 15:02:17.353774 kernel: raid6: neonx8 gen() 15777 MB/s Nov 5 15:02:17.353781 kernel: raid6: neonx4 gen() 15692 MB/s Nov 5 15:02:17.353790 kernel: raid6: neonx2 gen() 13170 MB/s Nov 5 15:02:17.353798 kernel: raid6: neonx1 gen() 10463 MB/s Nov 5 15:02:17.353805 kernel: raid6: int64x8 gen() 6865 MB/s Nov 5 15:02:17.353813 kernel: raid6: int64x4 gen() 7318 MB/s Nov 5 15:02:17.353820 kernel: raid6: int64x2 gen() 6079 MB/s Nov 5 15:02:17.353828 kernel: raid6: int64x1 gen() 5031 MB/s Nov 5 15:02:17.353835 kernel: raid6: using algorithm neonx8 gen() 15777 MB/s Nov 5 15:02:17.353844 kernel: raid6: .... xor() 12045 MB/s, rmw enabled Nov 5 15:02:17.353851 kernel: raid6: using neon recovery algorithm Nov 5 15:02:17.353859 kernel: xor: measuring software checksum speed Nov 5 15:02:17.353867 kernel: 8regs : 21539 MB/sec Nov 5 15:02:17.353874 kernel: 32regs : 20681 MB/sec Nov 5 15:02:17.353881 kernel: arm64_neon : 27946 MB/sec Nov 5 15:02:17.353889 kernel: xor: using function: arm64_neon (27946 MB/sec) Nov 5 15:02:17.353896 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 5 15:02:17.353905 kernel: BTRFS: device fsid d8f84a83-fd8b-4c0e-831a-0d7c5ff234be devid 1 transid 36 /dev/mapper/usr (253:0) scanned by mount (205) Nov 5 15:02:17.353913 kernel: BTRFS info (device dm-0): first mount of filesystem d8f84a83-fd8b-4c0e-831a-0d7c5ff234be Nov 5 15:02:17.353921 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:02:17.353929 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 5 15:02:17.353936 kernel: BTRFS info (device dm-0): enabling free space tree Nov 5 15:02:17.353943 kernel: loop: module loaded Nov 5 15:02:17.353951 kernel: loop0: detected capacity change from 0 to 91464 Nov 5 15:02:17.353960 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 5 15:02:17.353968 systemd[1]: Successfully made /usr/ read-only. Nov 5 15:02:17.353979 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:02:17.353987 systemd[1]: Detected virtualization kvm. Nov 5 15:02:17.353995 systemd[1]: Detected architecture arm64. Nov 5 15:02:17.354004 systemd[1]: Running in initrd. Nov 5 15:02:17.354012 systemd[1]: No hostname configured, using default hostname. Nov 5 15:02:17.354020 systemd[1]: Hostname set to . Nov 5 15:02:17.354028 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 5 15:02:17.354036 systemd[1]: Queued start job for default target initrd.target. Nov 5 15:02:17.354045 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:02:17.354053 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:02:17.354063 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:02:17.354071 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 5 15:02:17.354080 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:02:17.354089 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 5 15:02:17.354097 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 5 15:02:17.354106 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:02:17.354115 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:02:17.354123 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:02:17.354131 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:02:17.354140 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:02:17.354148 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:02:17.354155 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:02:17.354165 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:02:17.354173 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:02:17.354181 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 5 15:02:17.354190 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 5 15:02:17.354205 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:02:17.354216 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:02:17.354225 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:02:17.354233 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:02:17.354242 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 5 15:02:17.354260 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 5 15:02:17.354269 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:02:17.354278 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 5 15:02:17.354289 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 5 15:02:17.354297 systemd[1]: Starting systemd-fsck-usr.service... Nov 5 15:02:17.354306 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:02:17.354331 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:02:17.354340 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:02:17.354351 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 5 15:02:17.354360 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:02:17.354368 systemd[1]: Finished systemd-fsck-usr.service. Nov 5 15:02:17.354380 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 15:02:17.354408 systemd-journald[345]: Collecting audit messages is disabled. Nov 5 15:02:17.354431 systemd-journald[345]: Journal started Nov 5 15:02:17.354450 systemd-journald[345]: Runtime Journal (/run/log/journal/20555a9747d44962aff82d10d063ba02) is 6M, max 48.5M, 42.4M free. Nov 5 15:02:17.360392 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 5 15:02:17.360429 kernel: Bridge firewalling registered Nov 5 15:02:17.359356 systemd-modules-load[346]: Inserted module 'br_netfilter' Nov 5 15:02:17.363653 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:02:17.365330 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:02:17.369445 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:02:17.372098 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:02:17.375991 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 15:02:17.378001 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:02:17.380496 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:02:17.394963 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:02:17.407771 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:02:17.409309 systemd-tmpfiles[369]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 5 15:02:17.410122 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:02:17.413678 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:02:17.415714 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:02:17.419084 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:02:17.424960 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 5 15:02:17.453430 dracut-cmdline[388]: 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=15758474ef4cace68fb389c1b75e821ab8f30d9b752a28429e0459793723ea7b Nov 5 15:02:17.456956 systemd-resolved[385]: Positive Trust Anchors: Nov 5 15:02:17.456979 systemd-resolved[385]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:02:17.456983 systemd-resolved[385]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:02:17.457018 systemd-resolved[385]: 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 5 15:02:17.479849 systemd-resolved[385]: Defaulting to hostname 'linux'. Nov 5 15:02:17.481586 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:02:17.482829 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:02:17.533340 kernel: Loading iSCSI transport class v2.0-870. Nov 5 15:02:17.542332 kernel: iscsi: registered transport (tcp) Nov 5 15:02:17.556339 kernel: iscsi: registered transport (qla4xxx) Nov 5 15:02:17.556369 kernel: QLogic iSCSI HBA Driver Nov 5 15:02:17.576887 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:02:17.594474 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:02:17.596082 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:02:17.644525 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 5 15:02:17.647043 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 5 15:02:17.648882 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 5 15:02:17.693765 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:02:17.697081 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:02:17.735501 systemd-udevd[625]: Using default interface naming scheme 'v257'. Nov 5 15:02:17.743244 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:02:17.745659 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 5 15:02:17.770364 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:02:17.773529 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:02:17.776041 dracut-pre-trigger[703]: rd.md=0: removing MD RAID activation Nov 5 15:02:17.798769 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:02:17.803692 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:02:17.821632 systemd-networkd[738]: lo: Link UP Nov 5 15:02:17.821640 systemd-networkd[738]: lo: Gained carrier Nov 5 15:02:17.822290 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:02:17.823703 systemd[1]: Reached target network.target - Network. Nov 5 15:02:17.858571 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:02:17.862701 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 5 15:02:17.903383 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 5 15:02:17.911599 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 5 15:02:17.926686 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 5 15:02:17.934397 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 5 15:02:17.937458 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 5 15:02:17.950825 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:02:17.952778 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:02:17.954152 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:02:17.959654 disk-uuid[803]: Primary Header is updated. Nov 5 15:02:17.959654 disk-uuid[803]: Secondary Entries is updated. Nov 5 15:02:17.959654 disk-uuid[803]: Secondary Header is updated. Nov 5 15:02:17.957452 systemd-networkd[738]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:02:17.957456 systemd-networkd[738]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:02:17.957873 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:02:17.958504 systemd-networkd[738]: eth0: Link UP Nov 5 15:02:17.958648 systemd-networkd[738]: eth0: Gained carrier Nov 5 15:02:17.958661 systemd-networkd[738]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:02:17.973383 systemd-networkd[738]: eth0: DHCPv4 address 10.0.0.39/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 5 15:02:17.993334 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:02:18.022508 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 5 15:02:18.024166 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:02:18.025816 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:02:18.028056 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:02:18.031227 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 5 15:02:18.065216 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:02:18.987998 disk-uuid[805]: Warning: The kernel is still using the old partition table. Nov 5 15:02:18.987998 disk-uuid[805]: The new table will be used at the next reboot or after you Nov 5 15:02:18.987998 disk-uuid[805]: run partprobe(8) or kpartx(8) Nov 5 15:02:18.987998 disk-uuid[805]: The operation has completed successfully. Nov 5 15:02:18.998307 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 5 15:02:18.998447 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 5 15:02:19.001575 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 5 15:02:19.029877 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (837) Nov 5 15:02:19.029922 kernel: BTRFS info (device vda6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:02:19.029934 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:02:19.033736 kernel: BTRFS info (device vda6): turning on async discard Nov 5 15:02:19.033766 kernel: BTRFS info (device vda6): enabling free space tree Nov 5 15:02:19.039336 kernel: BTRFS info (device vda6): last unmount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:02:19.040192 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 5 15:02:19.042379 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 5 15:02:19.147470 ignition[856]: Ignition 2.22.0 Nov 5 15:02:19.147488 ignition[856]: Stage: fetch-offline Nov 5 15:02:19.147525 ignition[856]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:02:19.147535 ignition[856]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 15:02:19.147625 ignition[856]: parsed url from cmdline: "" Nov 5 15:02:19.147628 ignition[856]: no config URL provided Nov 5 15:02:19.147632 ignition[856]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 15:02:19.147640 ignition[856]: no config at "/usr/lib/ignition/user.ign" Nov 5 15:02:19.147675 ignition[856]: op(1): [started] loading QEMU firmware config module Nov 5 15:02:19.147679 ignition[856]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 5 15:02:19.152933 ignition[856]: op(1): [finished] loading QEMU firmware config module Nov 5 15:02:19.160952 ignition[856]: parsing config with SHA512: df12629f69242c0b5c945f15f701642dd9cf019659b7f79c3f033210ce79208487d7a0d5fe4c0c32c910be63ad2b7f5e1e8b19ced3a6036a014996af42d8b388 Nov 5 15:02:19.163590 unknown[856]: fetched base config from "system" Nov 5 15:02:19.163601 unknown[856]: fetched user config from "qemu" Nov 5 15:02:19.163836 ignition[856]: fetch-offline: fetch-offline passed Nov 5 15:02:19.165646 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:02:19.163885 ignition[856]: Ignition finished successfully Nov 5 15:02:19.167873 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 5 15:02:19.168790 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 5 15:02:19.196645 ignition[870]: Ignition 2.22.0 Nov 5 15:02:19.196662 ignition[870]: Stage: kargs Nov 5 15:02:19.196805 ignition[870]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:02:19.196814 ignition[870]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 15:02:19.199840 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 5 15:02:19.197416 ignition[870]: kargs: kargs passed Nov 5 15:02:19.197463 ignition[870]: Ignition finished successfully Nov 5 15:02:19.202451 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 5 15:02:19.230535 ignition[878]: Ignition 2.22.0 Nov 5 15:02:19.230553 ignition[878]: Stage: disks Nov 5 15:02:19.230700 ignition[878]: no configs at "/usr/lib/ignition/base.d" Nov 5 15:02:19.230707 ignition[878]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 15:02:19.232914 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 5 15:02:19.231254 ignition[878]: disks: disks passed Nov 5 15:02:19.234275 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 5 15:02:19.231309 ignition[878]: Ignition finished successfully Nov 5 15:02:19.236283 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 5 15:02:19.238546 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:02:19.240275 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:02:19.242426 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:02:19.244936 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 5 15:02:19.272157 systemd-fsck[888]: ROOT: clean, 15/456736 files, 38230/456704 blocks Nov 5 15:02:19.276367 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 5 15:02:19.280532 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 5 15:02:19.351322 kernel: EXT4-fs (vda9): mounted filesystem 67ab558f-e1dc-496b-b18a-e9709809a3c4 r/w with ordered data mode. Quota mode: none. Nov 5 15:02:19.351835 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 5 15:02:19.353239 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 5 15:02:19.355956 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:02:19.357804 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 5 15:02:19.358903 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 5 15:02:19.358936 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 5 15:02:19.358960 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:02:19.365559 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 5 15:02:19.370382 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (896) Nov 5 15:02:19.369027 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 5 15:02:19.374945 kernel: BTRFS info (device vda6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:02:19.374981 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:02:19.378009 kernel: BTRFS info (device vda6): turning on async discard Nov 5 15:02:19.378061 kernel: BTRFS info (device vda6): enabling free space tree Nov 5 15:02:19.379077 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:02:19.408934 initrd-setup-root[920]: cut: /sysroot/etc/passwd: No such file or directory Nov 5 15:02:19.412446 initrd-setup-root[927]: cut: /sysroot/etc/group: No such file or directory Nov 5 15:02:19.416874 initrd-setup-root[934]: cut: /sysroot/etc/shadow: No such file or directory Nov 5 15:02:19.420628 initrd-setup-root[941]: cut: /sysroot/etc/gshadow: No such file or directory Nov 5 15:02:19.490534 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 5 15:02:19.492918 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 5 15:02:19.494648 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 5 15:02:19.510979 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 5 15:02:19.512547 kernel: BTRFS info (device vda6): last unmount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:02:19.529588 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 5 15:02:19.538451 systemd-networkd[738]: eth0: Gained IPv6LL Nov 5 15:02:19.544456 ignition[1010]: INFO : Ignition 2.22.0 Nov 5 15:02:19.544456 ignition[1010]: INFO : Stage: mount Nov 5 15:02:19.546123 ignition[1010]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:02:19.546123 ignition[1010]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 15:02:19.546123 ignition[1010]: INFO : mount: mount passed Nov 5 15:02:19.546123 ignition[1010]: INFO : Ignition finished successfully Nov 5 15:02:19.548347 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 5 15:02:19.550476 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 5 15:02:20.353448 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 15:02:20.371389 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1022) Nov 5 15:02:20.373760 kernel: BTRFS info (device vda6): first mount of filesystem 53018052-4eb1-4655-a725-a5d3199d5804 Nov 5 15:02:20.373802 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 15:02:20.376575 kernel: BTRFS info (device vda6): turning on async discard Nov 5 15:02:20.376618 kernel: BTRFS info (device vda6): enabling free space tree Nov 5 15:02:20.377947 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 15:02:20.408676 ignition[1039]: INFO : Ignition 2.22.0 Nov 5 15:02:20.408676 ignition[1039]: INFO : Stage: files Nov 5 15:02:20.410366 ignition[1039]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:02:20.410366 ignition[1039]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 15:02:20.410366 ignition[1039]: DEBUG : files: compiled without relabeling support, skipping Nov 5 15:02:20.413592 ignition[1039]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 5 15:02:20.413592 ignition[1039]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 5 15:02:20.416620 ignition[1039]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 5 15:02:20.417963 ignition[1039]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 5 15:02:20.417963 ignition[1039]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 5 15:02:20.417232 unknown[1039]: wrote ssh authorized keys file for user: core Nov 5 15:02:20.421878 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 5 15:02:20.421878 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 5 15:02:20.425483 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:02:20.427258 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 15:02:20.427258 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 5 15:02:20.431449 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 5 15:02:20.433919 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 5 15:02:20.433919 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Nov 5 15:02:20.898883 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 5 15:02:21.181677 ignition[1039]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Nov 5 15:02:21.181677 ignition[1039]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Nov 5 15:02:21.185304 ignition[1039]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 5 15:02:21.187584 ignition[1039]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 5 15:02:21.187584 ignition[1039]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Nov 5 15:02:21.187584 ignition[1039]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Nov 5 15:02:21.202579 ignition[1039]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 5 15:02:21.206911 ignition[1039]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 5 15:02:21.209641 ignition[1039]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Nov 5 15:02:21.209641 ignition[1039]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:02:21.209641 ignition[1039]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 5 15:02:21.209641 ignition[1039]: INFO : files: files passed Nov 5 15:02:21.209641 ignition[1039]: INFO : Ignition finished successfully Nov 5 15:02:21.210476 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 5 15:02:21.213370 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 5 15:02:21.216379 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 5 15:02:21.232495 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 5 15:02:21.237054 initrd-setup-root-after-ignition[1069]: grep: /sysroot/oem/oem-release: No such file or directory Nov 5 15:02:21.234356 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 5 15:02:21.239987 initrd-setup-root-after-ignition[1071]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:02:21.239987 initrd-setup-root-after-ignition[1071]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:02:21.244240 initrd-setup-root-after-ignition[1076]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 15:02:21.241360 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:02:21.242965 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 5 15:02:21.246404 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 5 15:02:21.294231 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 5 15:02:21.294380 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 5 15:02:21.296781 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 5 15:02:21.298661 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 5 15:02:21.300643 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 5 15:02:21.301450 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 5 15:02:21.335413 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:02:21.338190 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 5 15:02:21.359409 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 5 15:02:21.359626 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:02:21.361962 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:02:21.364054 systemd[1]: Stopped target timers.target - Timer Units. Nov 5 15:02:21.365893 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 5 15:02:21.366025 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 15:02:21.368719 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 5 15:02:21.370927 systemd[1]: Stopped target basic.target - Basic System. Nov 5 15:02:21.372667 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 5 15:02:21.374495 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 15:02:21.376500 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 5 15:02:21.378616 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 5 15:02:21.380675 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 5 15:02:21.382896 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 15:02:21.385120 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 5 15:02:21.387338 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 5 15:02:21.389223 systemd[1]: Stopped target swap.target - Swaps. Nov 5 15:02:21.390916 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 5 15:02:21.391062 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 5 15:02:21.393502 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:02:21.395705 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:02:21.397768 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 5 15:02:21.401388 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:02:21.402717 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 5 15:02:21.402852 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 5 15:02:21.406006 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 5 15:02:21.406147 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 15:02:21.408451 systemd[1]: Stopped target paths.target - Path Units. Nov 5 15:02:21.410325 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 5 15:02:21.410439 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:02:21.412697 systemd[1]: Stopped target slices.target - Slice Units. Nov 5 15:02:21.414389 systemd[1]: Stopped target sockets.target - Socket Units. Nov 5 15:02:21.416377 systemd[1]: iscsid.socket: Deactivated successfully. Nov 5 15:02:21.416472 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 15:02:21.418736 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 5 15:02:21.418828 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 15:02:21.420444 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 5 15:02:21.420564 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 15:02:21.422472 systemd[1]: ignition-files.service: Deactivated successfully. Nov 5 15:02:21.422579 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 5 15:02:21.425194 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 5 15:02:21.427740 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 5 15:02:21.428703 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 5 15:02:21.428842 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:02:21.431060 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 5 15:02:21.431187 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:02:21.433435 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 5 15:02:21.433546 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 15:02:21.439403 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 5 15:02:21.441345 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 5 15:02:21.449112 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 5 15:02:21.455812 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 5 15:02:21.457338 ignition[1096]: INFO : Ignition 2.22.0 Nov 5 15:02:21.457338 ignition[1096]: INFO : Stage: umount Nov 5 15:02:21.457338 ignition[1096]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 15:02:21.457338 ignition[1096]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 15:02:21.457338 ignition[1096]: INFO : umount: umount passed Nov 5 15:02:21.457338 ignition[1096]: INFO : Ignition finished successfully Nov 5 15:02:21.457364 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 5 15:02:21.461981 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 5 15:02:21.462089 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 5 15:02:21.463500 systemd[1]: Stopped target network.target - Network. Nov 5 15:02:21.465795 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 5 15:02:21.465865 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 5 15:02:21.467911 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 5 15:02:21.467960 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 5 15:02:21.470052 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 5 15:02:21.470106 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 5 15:02:21.473102 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 5 15:02:21.473151 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 5 15:02:21.475260 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 5 15:02:21.475334 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 5 15:02:21.477371 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 5 15:02:21.479229 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 5 15:02:21.482633 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 5 15:02:21.482757 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 5 15:02:21.492765 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 5 15:02:21.492905 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 5 15:02:21.496571 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 5 15:02:21.498382 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 5 15:02:21.498416 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:02:21.501334 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 5 15:02:21.502208 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 5 15:02:21.502288 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 15:02:21.504751 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 5 15:02:21.504798 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:02:21.506571 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 5 15:02:21.506617 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 5 15:02:21.508572 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:02:21.525632 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 5 15:02:21.525813 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:02:21.528189 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 5 15:02:21.528225 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 5 15:02:21.530202 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 5 15:02:21.530238 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:02:21.532097 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 5 15:02:21.532150 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 5 15:02:21.534914 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 5 15:02:21.534969 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 5 15:02:21.537700 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 15:02:21.537757 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 15:02:21.556030 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 5 15:02:21.557216 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 5 15:02:21.557297 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:02:21.559664 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 5 15:02:21.559717 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:02:21.561963 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 5 15:02:21.562010 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:02:21.564574 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 5 15:02:21.564622 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:02:21.566737 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:02:21.566786 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:02:21.569654 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 5 15:02:21.571341 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 5 15:02:21.572608 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 5 15:02:21.572684 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 5 15:02:21.575127 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 5 15:02:21.577375 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 5 15:02:21.605182 systemd[1]: Switching root. Nov 5 15:02:21.637528 systemd-journald[345]: Journal stopped Nov 5 15:02:22.396602 systemd-journald[345]: Received SIGTERM from PID 1 (systemd). Nov 5 15:02:22.396658 kernel: SELinux: policy capability network_peer_controls=1 Nov 5 15:02:22.396671 kernel: SELinux: policy capability open_perms=1 Nov 5 15:02:22.396681 kernel: SELinux: policy capability extended_socket_class=1 Nov 5 15:02:22.396693 kernel: SELinux: policy capability always_check_network=0 Nov 5 15:02:22.396704 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 5 15:02:22.396718 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 5 15:02:22.396728 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 5 15:02:22.396738 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 5 15:02:22.396751 kernel: SELinux: policy capability userspace_initial_context=0 Nov 5 15:02:22.396762 systemd[1]: Successfully loaded SELinux policy in 65.296ms. Nov 5 15:02:22.396779 kernel: audit: type=1403 audit(1762354941.806:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 5 15:02:22.396792 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.458ms. Nov 5 15:02:22.396805 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 15:02:22.396816 systemd[1]: Detected virtualization kvm. Nov 5 15:02:22.396827 systemd[1]: Detected architecture arm64. Nov 5 15:02:22.396837 systemd[1]: Detected first boot. Nov 5 15:02:22.396847 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 5 15:02:22.396857 kernel: NET: Registered PF_VSOCK protocol family Nov 5 15:02:22.396870 zram_generator::config[1142]: No configuration found. Nov 5 15:02:22.396883 systemd[1]: Populated /etc with preset unit settings. Nov 5 15:02:22.396896 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 5 15:02:22.396906 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 5 15:02:22.396918 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 5 15:02:22.396931 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 5 15:02:22.396942 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 5 15:02:22.396952 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 5 15:02:22.396964 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 5 15:02:22.396975 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 5 15:02:22.396986 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 5 15:02:22.396996 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 5 15:02:22.397008 systemd[1]: Created slice user.slice - User and Session Slice. Nov 5 15:02:22.397019 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 15:02:22.397030 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 15:02:22.397041 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 5 15:02:22.397051 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 5 15:02:22.397062 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 5 15:02:22.397074 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 15:02:22.397085 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 5 15:02:22.397095 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 15:02:22.397106 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 15:02:22.397124 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 5 15:02:22.397135 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 5 15:02:22.397145 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 5 15:02:22.397157 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 5 15:02:22.397168 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 15:02:22.397179 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 15:02:22.397189 systemd[1]: Reached target slices.target - Slice Units. Nov 5 15:02:22.397199 systemd[1]: Reached target swap.target - Swaps. Nov 5 15:02:22.397209 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 5 15:02:22.397220 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 5 15:02:22.397232 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 5 15:02:22.397243 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 15:02:22.397254 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 15:02:22.397265 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 15:02:22.397275 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 5 15:02:22.397291 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 5 15:02:22.397302 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 5 15:02:22.397350 systemd[1]: Mounting media.mount - External Media Directory... Nov 5 15:02:22.397362 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 5 15:02:22.397372 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 5 15:02:22.397383 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 5 15:02:22.397394 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 5 15:02:22.397405 systemd[1]: Reached target machines.target - Containers. Nov 5 15:02:22.397415 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 5 15:02:22.397429 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:02:22.397440 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 15:02:22.397451 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 5 15:02:22.397462 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:02:22.397474 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:02:22.397485 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:02:22.397495 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 5 15:02:22.397507 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:02:22.397519 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 5 15:02:22.397529 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 5 15:02:22.397540 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 5 15:02:22.397550 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 5 15:02:22.397560 systemd[1]: Stopped systemd-fsck-usr.service. Nov 5 15:02:22.397572 kernel: fuse: init (API version 7.41) Nov 5 15:02:22.397582 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 5 15:02:22.397592 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 15:02:22.397603 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 15:02:22.397619 kernel: ACPI: bus type drm_connector registered Nov 5 15:02:22.397633 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 15:02:22.397644 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 5 15:02:22.397655 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 5 15:02:22.397667 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 15:02:22.397679 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 5 15:02:22.397705 systemd-journald[1213]: Collecting audit messages is disabled. Nov 5 15:02:22.397731 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 5 15:02:22.397743 systemd-journald[1213]: Journal started Nov 5 15:02:22.397763 systemd-journald[1213]: Runtime Journal (/run/log/journal/20555a9747d44962aff82d10d063ba02) is 6M, max 48.5M, 42.4M free. Nov 5 15:02:22.397797 systemd[1]: Mounted media.mount - External Media Directory. Nov 5 15:02:22.165952 systemd[1]: Queued start job for default target multi-user.target. Nov 5 15:02:22.191630 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 5 15:02:22.192085 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 5 15:02:22.401736 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 15:02:22.402751 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 5 15:02:22.403989 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 5 15:02:22.405279 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 5 15:02:22.408350 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 5 15:02:22.409835 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 15:02:22.411465 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 5 15:02:22.411659 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 5 15:02:22.413067 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:02:22.413234 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:02:22.414690 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:02:22.414851 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:02:22.416182 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:02:22.416405 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:02:22.417832 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 5 15:02:22.417992 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 5 15:02:22.419412 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:02:22.419566 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:02:22.422356 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 15:02:22.423883 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 15:02:22.426244 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 5 15:02:22.429430 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 5 15:02:22.442380 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 15:02:22.444639 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 15:02:22.446728 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 5 15:02:22.449009 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 5 15:02:22.451062 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 5 15:02:22.452276 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 5 15:02:22.452330 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 15:02:22.454138 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 5 15:02:22.455602 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:02:22.463206 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 5 15:02:22.465443 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 5 15:02:22.466612 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:02:22.467789 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 5 15:02:22.469014 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:02:22.472457 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 15:02:22.473978 systemd-journald[1213]: Time spent on flushing to /var/log/journal/20555a9747d44962aff82d10d063ba02 is 16.841ms for 855 entries. Nov 5 15:02:22.473978 systemd-journald[1213]: System Journal (/var/log/journal/20555a9747d44962aff82d10d063ba02) is 8M, max 163.5M, 155.5M free. Nov 5 15:02:22.501899 systemd-journald[1213]: Received client request to flush runtime journal. Nov 5 15:02:22.501952 kernel: loop1: detected capacity change from 0 to 100624 Nov 5 15:02:22.475061 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 5 15:02:22.477545 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 15:02:22.480303 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 5 15:02:22.481746 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 5 15:02:22.485349 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 5 15:02:22.488869 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 5 15:02:22.491822 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 5 15:02:22.505942 systemd-tmpfiles[1260]: ACLs are not supported, ignoring. Nov 5 15:02:22.505952 systemd-tmpfiles[1260]: ACLs are not supported, ignoring. Nov 5 15:02:22.508012 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 5 15:02:22.511463 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 15:02:22.513368 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 15:02:22.517592 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 5 15:02:22.524490 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 5 15:02:22.527327 kernel: loop2: detected capacity change from 0 to 119344 Nov 5 15:02:22.543453 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 5 15:02:22.546211 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 15:02:22.548145 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 15:02:22.553334 kernel: loop3: detected capacity change from 0 to 211168 Nov 5 15:02:22.560583 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 5 15:02:22.567590 systemd-tmpfiles[1280]: ACLs are not supported, ignoring. Nov 5 15:02:22.567609 systemd-tmpfiles[1280]: ACLs are not supported, ignoring. Nov 5 15:02:22.570890 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 15:02:22.578433 kernel: loop4: detected capacity change from 0 to 100624 Nov 5 15:02:22.584353 kernel: loop5: detected capacity change from 0 to 119344 Nov 5 15:02:22.588914 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 5 15:02:22.590224 kernel: loop6: detected capacity change from 0 to 211168 Nov 5 15:02:22.596060 (sd-merge)[1285]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw'. Nov 5 15:02:22.599033 (sd-merge)[1285]: Merged extensions into '/usr'. Nov 5 15:02:22.602841 systemd[1]: Reload requested from client PID 1259 ('systemd-sysext') (unit systemd-sysext.service)... Nov 5 15:02:22.602937 systemd[1]: Reloading... Nov 5 15:02:22.650342 zram_generator::config[1319]: No configuration found. Nov 5 15:02:22.652636 systemd-resolved[1279]: Positive Trust Anchors: Nov 5 15:02:22.652658 systemd-resolved[1279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:02:22.652661 systemd-resolved[1279]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 5 15:02:22.652691 systemd-resolved[1279]: 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 5 15:02:22.660221 systemd-resolved[1279]: Defaulting to hostname 'linux'. Nov 5 15:02:22.792718 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 5 15:02:22.792985 systemd[1]: Reloading finished in 189 ms. Nov 5 15:02:22.809426 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 15:02:22.810994 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 5 15:02:22.814389 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 15:02:22.826389 systemd[1]: Starting ensure-sysext.service... Nov 5 15:02:22.828218 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 15:02:22.836420 systemd[1]: Reload requested from client PID 1352 ('systemctl') (unit ensure-sysext.service)... Nov 5 15:02:22.836432 systemd[1]: Reloading... Nov 5 15:02:22.841928 systemd-tmpfiles[1353]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 5 15:02:22.841964 systemd-tmpfiles[1353]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 5 15:02:22.842198 systemd-tmpfiles[1353]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 5 15:02:22.842429 systemd-tmpfiles[1353]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 5 15:02:22.843026 systemd-tmpfiles[1353]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 5 15:02:22.843210 systemd-tmpfiles[1353]: ACLs are not supported, ignoring. Nov 5 15:02:22.843251 systemd-tmpfiles[1353]: ACLs are not supported, ignoring. Nov 5 15:02:22.858819 systemd-tmpfiles[1353]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:02:22.858834 systemd-tmpfiles[1353]: Skipping /boot Nov 5 15:02:22.867531 systemd-tmpfiles[1353]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 15:02:22.867546 systemd-tmpfiles[1353]: Skipping /boot Nov 5 15:02:22.892341 zram_generator::config[1386]: No configuration found. Nov 5 15:02:23.016691 systemd[1]: Reloading finished in 179 ms. Nov 5 15:02:23.028975 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 5 15:02:23.045357 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 15:02:23.052886 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 15:02:23.054928 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 5 15:02:23.064562 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 5 15:02:23.068543 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 5 15:02:23.071023 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 15:02:23.073245 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 5 15:02:23.077601 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:02:23.088718 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:02:23.092043 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:02:23.095044 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:02:23.096371 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:02:23.096495 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 5 15:02:23.097618 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:02:23.100908 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:02:23.110718 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 5 15:02:23.114005 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:02:23.114147 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:02:23.115262 systemd-udevd[1429]: Using default interface naming scheme 'v257'. Nov 5 15:02:23.116685 augenrules[1449]: No rules Nov 5 15:02:23.117027 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:02:23.117214 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:02:23.119193 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 15:02:23.119396 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 15:02:23.122720 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 5 15:02:23.129595 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:02:23.133564 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:02:23.135937 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:02:23.143429 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 15:02:23.144731 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:02:23.144855 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 5 15:02:23.145609 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 15:02:23.148912 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 5 15:02:23.150846 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:02:23.151009 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:02:23.153071 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:02:23.153372 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:02:23.176658 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 15:02:23.178258 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 15:02:23.179619 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 15:02:23.189331 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 15:02:23.193370 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 15:02:23.195989 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 15:02:23.196040 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 5 15:02:23.198070 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 15:02:23.199345 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 5 15:02:23.207913 systemd[1]: Finished ensure-sysext.service. Nov 5 15:02:23.209161 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 15:02:23.209361 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 15:02:23.211094 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 15:02:23.211245 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 15:02:23.213070 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:02:23.213222 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 15:02:23.214233 augenrules[1483]: /sbin/augenrules: No change Nov 5 15:02:23.214971 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 15:02:23.215148 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 15:02:23.230218 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 5 15:02:23.230277 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 15:02:23.230379 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 15:02:23.233966 augenrules[1516]: No rules Nov 5 15:02:23.234456 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 5 15:02:23.236074 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 15:02:23.238380 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 15:02:23.261122 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 5 15:02:23.266653 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 5 15:02:23.299404 systemd-networkd[1491]: lo: Link UP Nov 5 15:02:23.299421 systemd-networkd[1491]: lo: Gained carrier Nov 5 15:02:23.300422 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 15:02:23.300731 systemd-networkd[1491]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:02:23.300735 systemd-networkd[1491]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 15:02:23.301792 systemd-networkd[1491]: eth0: Link UP Nov 5 15:02:23.302060 systemd-networkd[1491]: eth0: Gained carrier Nov 5 15:02:23.302129 systemd-networkd[1491]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 5 15:02:23.302532 systemd[1]: Reached target network.target - Network. Nov 5 15:02:23.306100 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 5 15:02:23.309894 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 5 15:02:23.312180 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 5 15:02:23.326606 systemd-networkd[1491]: eth0: DHCPv4 address 10.0.0.39/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 5 15:02:23.341469 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 5 15:02:23.343810 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 5 15:02:23.346023 systemd[1]: Reached target time-set.target - System Time Set. Nov 5 15:02:23.347873 systemd-timesyncd[1515]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 5 15:02:23.347998 systemd-timesyncd[1515]: Initial clock synchronization to Wed 2025-11-05 15:02:22.994057 UTC. Nov 5 15:02:23.368414 ldconfig[1421]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 5 15:02:23.374447 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 5 15:02:23.387552 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 5 15:02:23.390509 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 15:02:23.409601 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 5 15:02:23.437452 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 15:02:23.440027 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 15:02:23.441200 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 5 15:02:23.442509 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 5 15:02:23.443922 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 5 15:02:23.445086 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 5 15:02:23.446380 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 5 15:02:23.447753 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 5 15:02:23.447787 systemd[1]: Reached target paths.target - Path Units. Nov 5 15:02:23.448688 systemd[1]: Reached target timers.target - Timer Units. Nov 5 15:02:23.450226 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 5 15:02:23.452672 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 5 15:02:23.455476 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 5 15:02:23.456891 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 5 15:02:23.458189 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 5 15:02:23.461246 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 5 15:02:23.462736 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 5 15:02:23.464507 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 5 15:02:23.465706 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 15:02:23.466718 systemd[1]: Reached target basic.target - Basic System. Nov 5 15:02:23.467721 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:02:23.467755 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 5 15:02:23.468777 systemd[1]: Starting containerd.service - containerd container runtime... Nov 5 15:02:23.470855 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 5 15:02:23.472810 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 5 15:02:23.474923 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 5 15:02:23.476896 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 5 15:02:23.477988 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 5 15:02:23.478967 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 5 15:02:23.481456 jq[1563]: false Nov 5 15:02:23.482462 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 5 15:02:23.484665 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 5 15:02:23.488464 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 5 15:02:23.489617 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 5 15:02:23.489970 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 5 15:02:23.490484 systemd[1]: Starting update-engine.service - Update Engine... Nov 5 15:02:23.491597 extend-filesystems[1564]: Found /dev/vda6 Nov 5 15:02:23.494488 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 5 15:02:23.495873 extend-filesystems[1564]: Found /dev/vda9 Nov 5 15:02:23.498735 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 5 15:02:23.500387 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 5 15:02:23.500571 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 5 15:02:23.500809 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 5 15:02:23.500960 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 5 15:02:23.503406 extend-filesystems[1564]: Checking size of /dev/vda9 Nov 5 15:02:23.504523 systemd[1]: motdgen.service: Deactivated successfully. Nov 5 15:02:23.511478 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 5 15:02:23.512549 extend-filesystems[1564]: Resized partition /dev/vda9 Nov 5 15:02:23.513583 jq[1575]: true Nov 5 15:02:23.513733 extend-filesystems[1597]: resize2fs 1.47.3 (8-Jul-2025) Nov 5 15:02:23.524591 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 1784827 blocks Nov 5 15:02:23.530688 (ntainerd)[1598]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 5 15:02:23.536339 jq[1599]: true Nov 5 15:02:23.550840 dbus-daemon[1561]: [system] SELinux support is enabled Nov 5 15:02:23.551040 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 5 15:02:23.552337 update_engine[1574]: I20251105 15:02:23.551580 1574 main.cc:92] Flatcar Update Engine starting Nov 5 15:02:23.558987 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 5 15:02:23.559015 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 5 15:02:23.560649 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 5 15:02:23.560673 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 5 15:02:23.563155 update_engine[1574]: I20251105 15:02:23.562752 1574 update_check_scheduler.cc:74] Next update check in 8m16s Nov 5 15:02:23.578930 kernel: EXT4-fs (vda9): resized filesystem to 1784827 Nov 5 15:02:23.562959 systemd[1]: Started update-engine.service - Update Engine. Nov 5 15:02:23.565559 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 5 15:02:23.579596 extend-filesystems[1597]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 5 15:02:23.579596 extend-filesystems[1597]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 5 15:02:23.579596 extend-filesystems[1597]: The filesystem on /dev/vda9 is now 1784827 (4k) blocks long. Nov 5 15:02:23.589417 extend-filesystems[1564]: Resized filesystem in /dev/vda9 Nov 5 15:02:23.592402 bash[1624]: Updated "/home/core/.ssh/authorized_keys" Nov 5 15:02:23.580634 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 5 15:02:23.580828 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 5 15:02:23.588232 systemd-logind[1572]: Watching system buttons on /dev/input/event0 (Power Button) Nov 5 15:02:23.589018 systemd-logind[1572]: New seat seat0. Nov 5 15:02:23.590950 systemd[1]: Started systemd-logind.service - User Login Management. Nov 5 15:02:23.595812 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 5 15:02:23.597804 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 5 15:02:23.626498 locksmithd[1616]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 5 15:02:23.706283 containerd[1598]: time="2025-11-05T15:02:23Z" 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 5 15:02:23.706865 containerd[1598]: time="2025-11-05T15:02:23.706807800Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 5 15:02:23.715299 containerd[1598]: time="2025-11-05T15:02:23.715256600Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10µs" Nov 5 15:02:23.715299 containerd[1598]: time="2025-11-05T15:02:23.715286760Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 5 15:02:23.715381 containerd[1598]: time="2025-11-05T15:02:23.715324880Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 5 15:02:23.715492 containerd[1598]: time="2025-11-05T15:02:23.715460080Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 5 15:02:23.715492 containerd[1598]: time="2025-11-05T15:02:23.715483920Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 5 15:02:23.715532 containerd[1598]: time="2025-11-05T15:02:23.715508200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 5 15:02:23.715569 containerd[1598]: time="2025-11-05T15:02:23.715556360Z" 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 5 15:02:23.715569 containerd[1598]: time="2025-11-05T15:02:23.715567360Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 5 15:02:23.715763 containerd[1598]: time="2025-11-05T15:02:23.715726640Z" 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 5 15:02:23.715763 containerd[1598]: time="2025-11-05T15:02:23.715749400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:02:23.715763 containerd[1598]: time="2025-11-05T15:02:23.715759960Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 5 15:02:23.715823 containerd[1598]: time="2025-11-05T15:02:23.715768280Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 5 15:02:23.715843 containerd[1598]: time="2025-11-05T15:02:23.715832200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 5 15:02:23.716028 containerd[1598]: time="2025-11-05T15:02:23.715996800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 5 15:02:23.716052 containerd[1598]: time="2025-11-05T15:02:23.716038680Z" 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 5 15:02:23.716082 containerd[1598]: time="2025-11-05T15:02:23.716052200Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 5 15:02:23.716102 containerd[1598]: time="2025-11-05T15:02:23.716080800Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 5 15:02:23.716302 containerd[1598]: time="2025-11-05T15:02:23.716279360Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 5 15:02:23.716401 containerd[1598]: time="2025-11-05T15:02:23.716381960Z" level=info msg="metadata content store policy set" policy=shared Nov 5 15:02:23.719721 containerd[1598]: time="2025-11-05T15:02:23.719693280Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 5 15:02:23.719796 containerd[1598]: time="2025-11-05T15:02:23.719747400Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 5 15:02:23.719796 containerd[1598]: time="2025-11-05T15:02:23.719769520Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 5 15:02:23.719796 containerd[1598]: time="2025-11-05T15:02:23.719781560Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 5 15:02:23.719796 containerd[1598]: time="2025-11-05T15:02:23.719792920Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 5 15:02:23.719880 containerd[1598]: time="2025-11-05T15:02:23.719805960Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 5 15:02:23.719880 containerd[1598]: time="2025-11-05T15:02:23.719822280Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 5 15:02:23.719880 containerd[1598]: time="2025-11-05T15:02:23.719835320Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 5 15:02:23.719880 containerd[1598]: time="2025-11-05T15:02:23.719845640Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 5 15:02:23.719880 containerd[1598]: time="2025-11-05T15:02:23.719856320Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 5 15:02:23.719880 containerd[1598]: time="2025-11-05T15:02:23.719865600Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 5 15:02:23.719880 containerd[1598]: time="2025-11-05T15:02:23.719878000Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 5 15:02:23.720010 containerd[1598]: time="2025-11-05T15:02:23.719985800Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 5 15:02:23.720039 containerd[1598]: time="2025-11-05T15:02:23.720014080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 5 15:02:23.720039 containerd[1598]: time="2025-11-05T15:02:23.720031840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 5 15:02:23.720074 containerd[1598]: time="2025-11-05T15:02:23.720045600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 5 15:02:23.720074 containerd[1598]: time="2025-11-05T15:02:23.720056640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 5 15:02:23.720074 containerd[1598]: time="2025-11-05T15:02:23.720070160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 5 15:02:23.720123 containerd[1598]: time="2025-11-05T15:02:23.720081240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 5 15:02:23.720123 containerd[1598]: time="2025-11-05T15:02:23.720091720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 5 15:02:23.720123 containerd[1598]: time="2025-11-05T15:02:23.720102880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 5 15:02:23.720123 containerd[1598]: time="2025-11-05T15:02:23.720115640Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 5 15:02:23.720190 containerd[1598]: time="2025-11-05T15:02:23.720125720Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 5 15:02:23.720364 containerd[1598]: time="2025-11-05T15:02:23.720341800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 5 15:02:23.720401 containerd[1598]: time="2025-11-05T15:02:23.720365120Z" level=info msg="Start snapshots syncer" Nov 5 15:02:23.720401 containerd[1598]: time="2025-11-05T15:02:23.720393840Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 5 15:02:23.721182 containerd[1598]: time="2025-11-05T15:02:23.720971320Z" 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 5 15:02:23.721182 containerd[1598]: time="2025-11-05T15:02:23.721042560Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 5 15:02:23.721350 containerd[1598]: time="2025-11-05T15:02:23.721131080Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 5 15:02:23.721457 containerd[1598]: time="2025-11-05T15:02:23.721422480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 5 15:02:23.721483 containerd[1598]: time="2025-11-05T15:02:23.721469320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 5 15:02:23.721501 containerd[1598]: time="2025-11-05T15:02:23.721488640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 5 15:02:23.721519 containerd[1598]: time="2025-11-05T15:02:23.721504960Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 5 15:02:23.721536 containerd[1598]: time="2025-11-05T15:02:23.721521400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 5 15:02:23.721565 containerd[1598]: time="2025-11-05T15:02:23.721536120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 5 15:02:23.721565 containerd[1598]: time="2025-11-05T15:02:23.721551880Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 5 15:02:23.721598 containerd[1598]: time="2025-11-05T15:02:23.721581760Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 5 15:02:23.721619 containerd[1598]: time="2025-11-05T15:02:23.721598360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 5 15:02:23.721619 containerd[1598]: time="2025-11-05T15:02:23.721613320Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 5 15:02:23.721740 containerd[1598]: time="2025-11-05T15:02:23.721699080Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 5 15:02:23.721938 containerd[1598]: time="2025-11-05T15:02:23.721798040Z" 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 5 15:02:23.721967 containerd[1598]: time="2025-11-05T15:02:23.721940440Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 5 15:02:23.721992 containerd[1598]: time="2025-11-05T15:02:23.721964400Z" 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 5 15:02:23.722011 containerd[1598]: time="2025-11-05T15:02:23.721986040Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 5 15:02:23.722011 containerd[1598]: time="2025-11-05T15:02:23.722006320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 5 15:02:23.722043 containerd[1598]: time="2025-11-05T15:02:23.722023840Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 5 15:02:23.722119 containerd[1598]: time="2025-11-05T15:02:23.722102840Z" level=info msg="runtime interface created" Nov 5 15:02:23.722119 containerd[1598]: time="2025-11-05T15:02:23.722115440Z" level=info msg="created NRI interface" Nov 5 15:02:23.722182 containerd[1598]: time="2025-11-05T15:02:23.722136960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 5 15:02:23.722182 containerd[1598]: time="2025-11-05T15:02:23.722161440Z" level=info msg="Connect containerd service" Nov 5 15:02:23.722228 containerd[1598]: time="2025-11-05T15:02:23.722206360Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 5 15:02:23.723174 containerd[1598]: time="2025-11-05T15:02:23.723139280Z" 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 5 15:02:23.787755 containerd[1598]: time="2025-11-05T15:02:23.787698800Z" level=info msg="Start subscribing containerd event" Nov 5 15:02:23.787939 containerd[1598]: time="2025-11-05T15:02:23.787923560Z" level=info msg="Start recovering state" Nov 5 15:02:23.788183 containerd[1598]: time="2025-11-05T15:02:23.788166680Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 5 15:02:23.788370 containerd[1598]: time="2025-11-05T15:02:23.788353560Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 5 15:02:23.788446 containerd[1598]: time="2025-11-05T15:02:23.788259960Z" level=info msg="Start event monitor" Nov 5 15:02:23.788509 containerd[1598]: time="2025-11-05T15:02:23.788488360Z" level=info msg="Start cni network conf syncer for default" Nov 5 15:02:23.788555 containerd[1598]: time="2025-11-05T15:02:23.788543680Z" level=info msg="Start streaming server" Nov 5 15:02:23.788673 containerd[1598]: time="2025-11-05T15:02:23.788588600Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 5 15:02:23.788673 containerd[1598]: time="2025-11-05T15:02:23.788599400Z" level=info msg="runtime interface starting up..." Nov 5 15:02:23.788673 containerd[1598]: time="2025-11-05T15:02:23.788606360Z" level=info msg="starting plugins..." Nov 5 15:02:23.788673 containerd[1598]: time="2025-11-05T15:02:23.788625640Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 5 15:02:23.788898 containerd[1598]: time="2025-11-05T15:02:23.788883200Z" level=info msg="containerd successfully booted in 0.082964s" Nov 5 15:02:23.789034 systemd[1]: Started containerd.service - containerd container runtime. Nov 5 15:02:24.147691 sshd_keygen[1586]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 5 15:02:24.168448 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 5 15:02:24.172614 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 5 15:02:24.200526 systemd[1]: issuegen.service: Deactivated successfully. Nov 5 15:02:24.201242 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 5 15:02:24.204468 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 5 15:02:24.222886 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 5 15:02:24.227554 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 5 15:02:24.232926 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 5 15:02:24.236644 systemd[1]: Reached target getty.target - Login Prompts. Nov 5 15:02:25.106451 systemd-networkd[1491]: eth0: Gained IPv6LL Nov 5 15:02:25.110413 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 5 15:02:25.112902 systemd[1]: Reached target network-online.target - Network is Online. Nov 5 15:02:25.117867 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 5 15:02:25.121585 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:02:25.131279 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 5 15:02:25.149172 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 5 15:02:25.157335 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 5 15:02:25.159666 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 5 15:02:25.165135 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 5 15:02:25.678796 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:02:25.680389 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 5 15:02:25.682862 systemd[1]: Startup finished in 1.172s (kernel) + 4.708s (initrd) + 3.942s (userspace) = 9.823s. Nov 5 15:02:25.683039 (kubelet)[1694]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 5 15:02:26.017217 kubelet[1694]: E1105 15:02:26.017101 1694 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 5 15:02:26.019357 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 5 15:02:26.019485 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 5 15:02:26.023392 systemd[1]: kubelet.service: Consumed 748ms CPU time, 256.7M memory peak. Nov 5 15:02:28.121096 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 5 15:02:28.122294 systemd[1]: Started sshd@0-10.0.0.39:22-10.0.0.1:58132.service - OpenSSH per-connection server daemon (10.0.0.1:58132). Nov 5 15:02:28.196197 sshd[1707]: Accepted publickey for core from 10.0.0.1 port 58132 ssh2: RSA SHA256:nM3EkzhYnY1k7HKfBVIgLIVO2VgoKZbQ4dF/3C6QndI Nov 5 15:02:28.198097 sshd-session[1707]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:02:28.203817 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 5 15:02:28.204812 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 5 15:02:28.210068 systemd-logind[1572]: New session 1 of user core. Nov 5 15:02:28.231371 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 5 15:02:28.233776 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 5 15:02:28.251226 (systemd)[1712]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:02:28.253572 systemd-logind[1572]: New session c1 of user core. Nov 5 15:02:28.349216 systemd[1712]: Queued start job for default target default.target. Nov 5 15:02:28.369134 systemd[1712]: Created slice app.slice - User Application Slice. Nov 5 15:02:28.369161 systemd[1712]: Reached target paths.target - Paths. Nov 5 15:02:28.369202 systemd[1712]: Reached target timers.target - Timers. Nov 5 15:02:28.370323 systemd[1712]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 5 15:02:28.379214 systemd[1712]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 5 15:02:28.379275 systemd[1712]: Reached target sockets.target - Sockets. Nov 5 15:02:28.379331 systemd[1712]: Reached target basic.target - Basic System. Nov 5 15:02:28.379361 systemd[1712]: Reached target default.target - Main User Target. Nov 5 15:02:28.379385 systemd[1712]: Startup finished in 120ms. Nov 5 15:02:28.379496 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 5 15:02:28.381069 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 5 15:02:28.445799 systemd[1]: Started sshd@1-10.0.0.39:22-10.0.0.1:58148.service - OpenSSH per-connection server daemon (10.0.0.1:58148). Nov 5 15:02:28.494923 sshd[1723]: Accepted publickey for core from 10.0.0.1 port 58148 ssh2: RSA SHA256:nM3EkzhYnY1k7HKfBVIgLIVO2VgoKZbQ4dF/3C6QndI Nov 5 15:02:28.495954 sshd-session[1723]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:02:28.500156 systemd-logind[1572]: New session 2 of user core. Nov 5 15:02:28.508517 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 5 15:02:28.559052 sshd[1726]: Connection closed by 10.0.0.1 port 58148 Nov 5 15:02:28.559384 sshd-session[1723]: pam_unix(sshd:session): session closed for user core Nov 5 15:02:28.567086 systemd[1]: sshd@1-10.0.0.39:22-10.0.0.1:58148.service: Deactivated successfully. Nov 5 15:02:28.569020 systemd[1]: session-2.scope: Deactivated successfully. Nov 5 15:02:28.569735 systemd-logind[1572]: Session 2 logged out. Waiting for processes to exit. Nov 5 15:02:28.572189 systemd[1]: Started sshd@2-10.0.0.39:22-10.0.0.1:58158.service - OpenSSH per-connection server daemon (10.0.0.1:58158). Nov 5 15:02:28.572841 systemd-logind[1572]: Removed session 2. Nov 5 15:02:28.626914 sshd[1732]: Accepted publickey for core from 10.0.0.1 port 58158 ssh2: RSA SHA256:nM3EkzhYnY1k7HKfBVIgLIVO2VgoKZbQ4dF/3C6QndI Nov 5 15:02:28.628066 sshd-session[1732]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:02:28.632796 systemd-logind[1572]: New session 3 of user core. Nov 5 15:02:28.641480 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 5 15:02:28.687292 sshd[1735]: Connection closed by 10.0.0.1 port 58158 Nov 5 15:02:28.687619 sshd-session[1732]: pam_unix(sshd:session): session closed for user core Nov 5 15:02:28.697040 systemd[1]: sshd@2-10.0.0.39:22-10.0.0.1:58158.service: Deactivated successfully. Nov 5 15:02:28.699438 systemd[1]: session-3.scope: Deactivated successfully. Nov 5 15:02:28.700012 systemd-logind[1572]: Session 3 logged out. Waiting for processes to exit. Nov 5 15:02:28.702032 systemd[1]: Started sshd@3-10.0.0.39:22-10.0.0.1:58174.service - OpenSSH per-connection server daemon (10.0.0.1:58174). Nov 5 15:02:28.702615 systemd-logind[1572]: Removed session 3. Nov 5 15:02:28.755646 sshd[1741]: Accepted publickey for core from 10.0.0.1 port 58174 ssh2: RSA SHA256:nM3EkzhYnY1k7HKfBVIgLIVO2VgoKZbQ4dF/3C6QndI Nov 5 15:02:28.756898 sshd-session[1741]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:02:28.760698 systemd-logind[1572]: New session 4 of user core. Nov 5 15:02:28.776458 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 5 15:02:28.826826 sshd[1746]: Connection closed by 10.0.0.1 port 58174 Nov 5 15:02:28.827185 sshd-session[1741]: pam_unix(sshd:session): session closed for user core Nov 5 15:02:28.839128 systemd[1]: sshd@3-10.0.0.39:22-10.0.0.1:58174.service: Deactivated successfully. Nov 5 15:02:28.841603 systemd[1]: session-4.scope: Deactivated successfully. Nov 5 15:02:28.842197 systemd-logind[1572]: Session 4 logged out. Waiting for processes to exit. Nov 5 15:02:28.844147 systemd[1]: Started sshd@4-10.0.0.39:22-10.0.0.1:58186.service - OpenSSH per-connection server daemon (10.0.0.1:58186). Nov 5 15:02:28.845057 systemd-logind[1572]: Removed session 4. Nov 5 15:02:28.897155 sshd[1752]: Accepted publickey for core from 10.0.0.1 port 58186 ssh2: RSA SHA256:nM3EkzhYnY1k7HKfBVIgLIVO2VgoKZbQ4dF/3C6QndI Nov 5 15:02:28.898192 sshd-session[1752]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 15:02:28.902482 systemd-logind[1572]: New session 5 of user core. Nov 5 15:02:28.920464 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 5 15:02:28.976595 sudo[1756]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 5 15:02:28.976835 sudo[1756]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 15:02:28.987074 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 5 15:02:29.022180 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 5 15:02:29.024359 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 5 15:02:29.416605 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:02:29.416754 systemd[1]: kubelet.service: Consumed 748ms CPU time, 256.7M memory peak. Nov 5 15:02:29.418499 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:02:29.437701 systemd[1]: Reload requested from client PID 1800 ('systemctl') (unit session-5.scope)... Nov 5 15:02:29.437717 systemd[1]: Reloading... Nov 5 15:02:29.506335 zram_generator::config[1842]: No configuration found. Nov 5 15:02:29.687822 systemd[1]: Reloading finished in 249 ms. Nov 5 15:02:29.741757 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 5 15:02:29.741835 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 5 15:02:29.742131 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:02:29.742180 systemd[1]: kubelet.service: Consumed 95ms CPU time, 95M memory peak. Nov 5 15:02:29.743930 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 15:02:29.849257 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 15:02:29.853213 (kubelet)[1887]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 5 15:02:29.882479 kubelet[1887]: 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 5 15:02:29.882479 kubelet[1887]: 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 5 15:02:29.882479 kubelet[1887]: 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 5 15:02:29.882777 kubelet[1887]: I1105 15:02:29.882506 1887 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 5 15:02:30.642458 kubelet[1887]: I1105 15:02:30.641821 1887 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Nov 5 15:02:30.642458 kubelet[1887]: I1105 15:02:30.641853 1887 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 5 15:02:30.642458 kubelet[1887]: I1105 15:02:30.642246 1887 server.go:956] "Client rotation is on, will bootstrap in background" Nov 5 15:02:30.663069 kubelet[1887]: I1105 15:02:30.663030 1887 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 5 15:02:30.669874 kubelet[1887]: I1105 15:02:30.669854 1887 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Nov 5 15:02:30.672922 kubelet[1887]: I1105 15:02:30.672566 1887 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 5 15:02:30.675211 kubelet[1887]: I1105 15:02:30.674575 1887 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 5 15:02:30.675211 kubelet[1887]: I1105 15:02:30.674618 1887 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.39","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 5 15:02:30.675211 kubelet[1887]: I1105 15:02:30.674846 1887 topology_manager.go:138] "Creating topology manager with none policy" Nov 5 15:02:30.675211 kubelet[1887]: I1105 15:02:30.674855 1887 container_manager_linux.go:303] "Creating device plugin manager" Nov 5 15:02:30.675211 kubelet[1887]: I1105 15:02:30.675043 1887 state_mem.go:36] "Initialized new in-memory state store" Nov 5 15:02:30.678970 kubelet[1887]: I1105 15:02:30.678922 1887 kubelet.go:480] "Attempting to sync node with API server" Nov 5 15:02:30.679051 kubelet[1887]: I1105 15:02:30.679041 1887 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 5 15:02:30.679123 kubelet[1887]: I1105 15:02:30.679114 1887 kubelet.go:386] "Adding apiserver pod source" Nov 5 15:02:30.680160 kubelet[1887]: I1105 15:02:30.680140 1887 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 5 15:02:30.680848 kubelet[1887]: E1105 15:02:30.680740 1887 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:30.680848 kubelet[1887]: E1105 15:02:30.680804 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:30.681430 kubelet[1887]: I1105 15:02:30.681366 1887 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Nov 5 15:02:30.683356 kubelet[1887]: I1105 15:02:30.683330 1887 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Nov 5 15:02:30.683544 kubelet[1887]: W1105 15:02:30.683531 1887 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 5 15:02:30.685850 kubelet[1887]: I1105 15:02:30.685828 1887 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 5 15:02:30.685953 kubelet[1887]: I1105 15:02:30.685943 1887 server.go:1289] "Started kubelet" Nov 5 15:02:30.686282 kubelet[1887]: I1105 15:02:30.686228 1887 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 5 15:02:30.686566 kubelet[1887]: I1105 15:02:30.686548 1887 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 5 15:02:30.686989 kubelet[1887]: I1105 15:02:30.686950 1887 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Nov 5 15:02:30.690156 kubelet[1887]: I1105 15:02:30.690118 1887 server.go:317] "Adding debug handlers to kubelet server" Nov 5 15:02:30.691190 kubelet[1887]: I1105 15:02:30.691167 1887 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 5 15:02:30.691936 kubelet[1887]: I1105 15:02:30.691879 1887 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 5 15:02:30.692398 kubelet[1887]: E1105 15:02:30.692299 1887 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.39\" not found" Nov 5 15:02:30.693379 kubelet[1887]: I1105 15:02:30.692478 1887 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 5 15:02:30.693379 kubelet[1887]: I1105 15:02:30.692673 1887 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 5 15:02:30.693379 kubelet[1887]: I1105 15:02:30.692808 1887 reconciler.go:26] "Reconciler: start to sync state" Nov 5 15:02:30.693736 kubelet[1887]: I1105 15:02:30.693721 1887 factory.go:223] Registration of the systemd container factory successfully Nov 5 15:02:30.693973 kubelet[1887]: I1105 15:02:30.693954 1887 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 5 15:02:30.694354 kubelet[1887]: E1105 15:02:30.694331 1887 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 5 15:02:30.696454 kubelet[1887]: I1105 15:02:30.696404 1887 factory.go:223] Registration of the containerd container factory successfully Nov 5 15:02:30.704541 kubelet[1887]: E1105 15:02:30.704512 1887 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.39\" not found" node="10.0.0.39" Nov 5 15:02:30.706206 kubelet[1887]: I1105 15:02:30.706190 1887 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 5 15:02:30.706206 kubelet[1887]: I1105 15:02:30.706204 1887 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 5 15:02:30.706253 kubelet[1887]: I1105 15:02:30.706223 1887 state_mem.go:36] "Initialized new in-memory state store" Nov 5 15:02:30.790109 kubelet[1887]: I1105 15:02:30.790056 1887 policy_none.go:49] "None policy: Start" Nov 5 15:02:30.790109 kubelet[1887]: I1105 15:02:30.790107 1887 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 5 15:02:30.790109 kubelet[1887]: I1105 15:02:30.790121 1887 state_mem.go:35] "Initializing new in-memory state store" Nov 5 15:02:30.792559 kubelet[1887]: E1105 15:02:30.792502 1887 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.39\" not found" Nov 5 15:02:30.793827 kubelet[1887]: I1105 15:02:30.793803 1887 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Nov 5 15:02:30.796683 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 5 15:02:30.812587 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 5 15:02:30.815624 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 5 15:02:30.823206 kubelet[1887]: I1105 15:02:30.822846 1887 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Nov 5 15:02:30.823206 kubelet[1887]: I1105 15:02:30.822879 1887 status_manager.go:230] "Starting to sync pod status with apiserver" Nov 5 15:02:30.823206 kubelet[1887]: I1105 15:02:30.822899 1887 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 5 15:02:30.823206 kubelet[1887]: I1105 15:02:30.822905 1887 kubelet.go:2436] "Starting kubelet main sync loop" Nov 5 15:02:30.823206 kubelet[1887]: E1105 15:02:30.822945 1887 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 5 15:02:30.823206 kubelet[1887]: E1105 15:02:30.823059 1887 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Nov 5 15:02:30.823479 kubelet[1887]: I1105 15:02:30.823246 1887 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 5 15:02:30.823479 kubelet[1887]: I1105 15:02:30.823258 1887 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 5 15:02:30.825278 kubelet[1887]: E1105 15:02:30.824780 1887 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 5 15:02:30.825278 kubelet[1887]: E1105 15:02:30.824820 1887 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.39\" not found" Nov 5 15:02:30.825278 kubelet[1887]: I1105 15:02:30.824901 1887 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 5 15:02:30.924755 kubelet[1887]: I1105 15:02:30.924300 1887 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.39" Nov 5 15:02:30.928564 kubelet[1887]: I1105 15:02:30.928527 1887 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.39" Nov 5 15:02:30.935978 kubelet[1887]: I1105 15:02:30.935942 1887 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 5 15:02:30.936268 containerd[1598]: time="2025-11-05T15:02:30.936223529Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 5 15:02:30.936532 kubelet[1887]: I1105 15:02:30.936418 1887 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 5 15:02:31.370729 sudo[1756]: pam_unix(sudo:session): session closed for user root Nov 5 15:02:31.372230 sshd[1755]: Connection closed by 10.0.0.1 port 58186 Nov 5 15:02:31.372546 sshd-session[1752]: pam_unix(sshd:session): session closed for user core Nov 5 15:02:31.376328 systemd[1]: sshd@4-10.0.0.39:22-10.0.0.1:58186.service: Deactivated successfully. Nov 5 15:02:31.378018 systemd[1]: session-5.scope: Deactivated successfully. Nov 5 15:02:31.378276 systemd[1]: session-5.scope: Consumed 402ms CPU time, 76.5M memory peak. Nov 5 15:02:31.379290 systemd-logind[1572]: Session 5 logged out. Waiting for processes to exit. Nov 5 15:02:31.380463 systemd-logind[1572]: Removed session 5. Nov 5 15:02:31.645235 kubelet[1887]: I1105 15:02:31.644692 1887 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 5 15:02:31.645235 kubelet[1887]: I1105 15:02:31.644866 1887 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 5 15:02:31.645235 kubelet[1887]: I1105 15:02:31.645097 1887 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 5 15:02:31.645235 kubelet[1887]: I1105 15:02:31.645132 1887 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 5 15:02:31.681096 kubelet[1887]: I1105 15:02:31.681057 1887 apiserver.go:52] "Watching apiserver" Nov 5 15:02:31.681179 kubelet[1887]: E1105 15:02:31.681093 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:31.693734 systemd[1]: Created slice kubepods-besteffort-pod4908b571_7cb3_4afb_92fa_ad777c8a70d3.slice - libcontainer container kubepods-besteffort-pod4908b571_7cb3_4afb_92fa_ad777c8a70d3.slice. Nov 5 15:02:31.695429 kubelet[1887]: I1105 15:02:31.695382 1887 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 5 15:02:31.697089 kubelet[1887]: I1105 15:02:31.696873 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/e8ddff7b-6d27-4c42-b982-f14844d3d5ce-cni\") pod \"kube-flannel-ds-42sr6\" (UID: \"e8ddff7b-6d27-4c42-b982-f14844d3d5ce\") " pod="kube-flannel/kube-flannel-ds-42sr6" Nov 5 15:02:31.697089 kubelet[1887]: I1105 15:02:31.696901 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/e8ddff7b-6d27-4c42-b982-f14844d3d5ce-xtables-lock\") pod \"kube-flannel-ds-42sr6\" (UID: \"e8ddff7b-6d27-4c42-b982-f14844d3d5ce\") " pod="kube-flannel/kube-flannel-ds-42sr6" Nov 5 15:02:31.697089 kubelet[1887]: I1105 15:02:31.696919 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/4908b571-7cb3-4afb-92fa-ad777c8a70d3-kube-proxy\") pod \"kube-proxy-kjllc\" (UID: \"4908b571-7cb3-4afb-92fa-ad777c8a70d3\") " pod="kube-system/kube-proxy-kjllc" Nov 5 15:02:31.697089 kubelet[1887]: I1105 15:02:31.696942 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/4908b571-7cb3-4afb-92fa-ad777c8a70d3-xtables-lock\") pod \"kube-proxy-kjllc\" (UID: \"4908b571-7cb3-4afb-92fa-ad777c8a70d3\") " pod="kube-system/kube-proxy-kjllc" Nov 5 15:02:31.697089 kubelet[1887]: I1105 15:02:31.696964 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-m7m2f\" (UniqueName: \"kubernetes.io/projected/4908b571-7cb3-4afb-92fa-ad777c8a70d3-kube-api-access-m7m2f\") pod \"kube-proxy-kjllc\" (UID: \"4908b571-7cb3-4afb-92fa-ad777c8a70d3\") " pod="kube-system/kube-proxy-kjllc" Nov 5 15:02:31.697237 kubelet[1887]: I1105 15:02:31.696981 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/e8ddff7b-6d27-4c42-b982-f14844d3d5ce-run\") pod \"kube-flannel-ds-42sr6\" (UID: \"e8ddff7b-6d27-4c42-b982-f14844d3d5ce\") " pod="kube-flannel/kube-flannel-ds-42sr6" Nov 5 15:02:31.697237 kubelet[1887]: I1105 15:02:31.696998 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/e8ddff7b-6d27-4c42-b982-f14844d3d5ce-cni-plugin\") pod \"kube-flannel-ds-42sr6\" (UID: \"e8ddff7b-6d27-4c42-b982-f14844d3d5ce\") " pod="kube-flannel/kube-flannel-ds-42sr6" Nov 5 15:02:31.697237 kubelet[1887]: I1105 15:02:31.697027 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/e8ddff7b-6d27-4c42-b982-f14844d3d5ce-flannel-cfg\") pod \"kube-flannel-ds-42sr6\" (UID: \"e8ddff7b-6d27-4c42-b982-f14844d3d5ce\") " pod="kube-flannel/kube-flannel-ds-42sr6" Nov 5 15:02:31.697237 kubelet[1887]: I1105 15:02:31.697042 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-t4pv7\" (UniqueName: \"kubernetes.io/projected/e8ddff7b-6d27-4c42-b982-f14844d3d5ce-kube-api-access-t4pv7\") pod \"kube-flannel-ds-42sr6\" (UID: \"e8ddff7b-6d27-4c42-b982-f14844d3d5ce\") " pod="kube-flannel/kube-flannel-ds-42sr6" Nov 5 15:02:31.697237 kubelet[1887]: I1105 15:02:31.697079 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/4908b571-7cb3-4afb-92fa-ad777c8a70d3-lib-modules\") pod \"kube-proxy-kjllc\" (UID: \"4908b571-7cb3-4afb-92fa-ad777c8a70d3\") " pod="kube-system/kube-proxy-kjllc" Nov 5 15:02:31.704407 systemd[1]: Created slice kubepods-burstable-pode8ddff7b_6d27_4c42_b982_f14844d3d5ce.slice - libcontainer container kubepods-burstable-pode8ddff7b_6d27_4c42_b982_f14844d3d5ce.slice. Nov 5 15:02:32.002946 kubelet[1887]: E1105 15:02:32.002815 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:32.003857 containerd[1598]: time="2025-11-05T15:02:32.003821000Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-kjllc,Uid:4908b571-7cb3-4afb-92fa-ad777c8a70d3,Namespace:kube-system,Attempt:0,}" Nov 5 15:02:32.016399 kubelet[1887]: E1105 15:02:32.016359 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:32.017739 containerd[1598]: time="2025-11-05T15:02:32.017495158Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-42sr6,Uid:e8ddff7b-6d27-4c42-b982-f14844d3d5ce,Namespace:kube-flannel,Attempt:0,}" Nov 5 15:02:32.523749 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3111568571.mount: Deactivated successfully. Nov 5 15:02:32.530860 containerd[1598]: time="2025-11-05T15:02:32.530526434Z" 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 5 15:02:32.531992 containerd[1598]: time="2025-11-05T15:02:32.531761460Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Nov 5 15:02:32.532527 containerd[1598]: time="2025-11-05T15:02:32.532495614Z" 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 5 15:02:32.533230 containerd[1598]: time="2025-11-05T15:02:32.533179030Z" 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 5 15:02:32.534031 containerd[1598]: time="2025-11-05T15:02:32.533803752Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Nov 5 15:02:32.535797 containerd[1598]: time="2025-11-05T15:02:32.535753906Z" 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 5 15:02:32.536583 containerd[1598]: time="2025-11-05T15:02:32.536557153Z" 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 523.496422ms" Nov 5 15:02:32.537735 containerd[1598]: time="2025-11-05T15:02:32.537681881Z" 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 516.295594ms" Nov 5 15:02:32.558667 containerd[1598]: time="2025-11-05T15:02:32.558614892Z" level=info msg="connecting to shim f419ef6817f83176f1fa4a7ac2a4e8ead40bb3097264e75040ad1295e069826d" address="unix:///run/containerd/s/0830fb2eeeed3c7fae62a4067b5fda8531468e9fe78a8eccda9a5dc0193409d4" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:02:32.561120 containerd[1598]: time="2025-11-05T15:02:32.561078878Z" level=info msg="connecting to shim 6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4" address="unix:///run/containerd/s/5d279027c4ca9261c00ecd1291af0021908c717bde62acf1c260aa65cd500ad0" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:02:32.583474 systemd[1]: Started cri-containerd-6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4.scope - libcontainer container 6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4. Nov 5 15:02:32.585065 systemd[1]: Started cri-containerd-f419ef6817f83176f1fa4a7ac2a4e8ead40bb3097264e75040ad1295e069826d.scope - libcontainer container f419ef6817f83176f1fa4a7ac2a4e8ead40bb3097264e75040ad1295e069826d. Nov 5 15:02:32.612723 containerd[1598]: time="2025-11-05T15:02:32.612588835Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-kjllc,Uid:4908b571-7cb3-4afb-92fa-ad777c8a70d3,Namespace:kube-system,Attempt:0,} returns sandbox id \"f419ef6817f83176f1fa4a7ac2a4e8ead40bb3097264e75040ad1295e069826d\"" Nov 5 15:02:32.613755 kubelet[1887]: E1105 15:02:32.613724 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:32.617187 containerd[1598]: time="2025-11-05T15:02:32.617062102Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Nov 5 15:02:32.620774 containerd[1598]: time="2025-11-05T15:02:32.620706832Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-42sr6,Uid:e8ddff7b-6d27-4c42-b982-f14844d3d5ce,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4\"" Nov 5 15:02:32.622238 kubelet[1887]: E1105 15:02:32.622084 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:32.681884 kubelet[1887]: E1105 15:02:32.681837 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:33.493382 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3916845986.mount: Deactivated successfully. Nov 5 15:02:33.682590 kubelet[1887]: E1105 15:02:33.682548 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:33.752386 containerd[1598]: time="2025-11-05T15:02:33.752256025Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:33.752908 containerd[1598]: time="2025-11-05T15:02:33.752873979Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240108" Nov 5 15:02:33.753852 containerd[1598]: time="2025-11-05T15:02:33.753827887Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:33.756421 containerd[1598]: time="2025-11-05T15:02:33.756360524Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:33.757231 containerd[1598]: time="2025-11-05T15:02:33.757196027Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.140093287s" Nov 5 15:02:33.757280 containerd[1598]: time="2025-11-05T15:02:33.757231272Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Nov 5 15:02:33.758756 containerd[1598]: time="2025-11-05T15:02:33.758727591Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Nov 5 15:02:33.764135 containerd[1598]: time="2025-11-05T15:02:33.764082878Z" level=info msg="CreateContainer within sandbox \"f419ef6817f83176f1fa4a7ac2a4e8ead40bb3097264e75040ad1295e069826d\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 5 15:02:33.773211 containerd[1598]: time="2025-11-05T15:02:33.771870947Z" level=info msg="Container 484d2f154e72dd0c6a1b94209d895c02d18ed377e9a74df433555fa0667cc236: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:02:33.779540 containerd[1598]: time="2025-11-05T15:02:33.779502406Z" level=info msg="CreateContainer within sandbox \"f419ef6817f83176f1fa4a7ac2a4e8ead40bb3097264e75040ad1295e069826d\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"484d2f154e72dd0c6a1b94209d895c02d18ed377e9a74df433555fa0667cc236\"" Nov 5 15:02:33.780565 containerd[1598]: time="2025-11-05T15:02:33.780537421Z" level=info msg="StartContainer for \"484d2f154e72dd0c6a1b94209d895c02d18ed377e9a74df433555fa0667cc236\"" Nov 5 15:02:33.781926 containerd[1598]: time="2025-11-05T15:02:33.781901482Z" level=info msg="connecting to shim 484d2f154e72dd0c6a1b94209d895c02d18ed377e9a74df433555fa0667cc236" address="unix:///run/containerd/s/0830fb2eeeed3c7fae62a4067b5fda8531468e9fe78a8eccda9a5dc0193409d4" protocol=ttrpc version=3 Nov 5 15:02:33.804491 systemd[1]: Started cri-containerd-484d2f154e72dd0c6a1b94209d895c02d18ed377e9a74df433555fa0667cc236.scope - libcontainer container 484d2f154e72dd0c6a1b94209d895c02d18ed377e9a74df433555fa0667cc236. Nov 5 15:02:33.843777 containerd[1598]: time="2025-11-05T15:02:33.843655649Z" level=info msg="StartContainer for \"484d2f154e72dd0c6a1b94209d895c02d18ed377e9a74df433555fa0667cc236\" returns successfully" Nov 5 15:02:34.683573 kubelet[1887]: E1105 15:02:34.683516 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:34.839735 kubelet[1887]: E1105 15:02:34.839695 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:34.853864 kubelet[1887]: I1105 15:02:34.853768 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-kjllc" podStartSLOduration=3.712230551 podStartE2EDuration="4.853754031s" podCreationTimestamp="2025-11-05 15:02:30 +0000 UTC" firstStartedPulling="2025-11-05 15:02:32.616467043 +0000 UTC m=+2.760147078" lastFinishedPulling="2025-11-05 15:02:33.757990522 +0000 UTC m=+3.901670558" observedRunningTime="2025-11-05 15:02:34.853643492 +0000 UTC m=+4.997323528" watchObservedRunningTime="2025-11-05 15:02:34.853754031 +0000 UTC m=+4.997434067" Nov 5 15:02:35.136856 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1643749331.mount: Deactivated successfully. Nov 5 15:02:35.170295 containerd[1598]: time="2025-11-05T15:02:35.170245597Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:35.171352 containerd[1598]: time="2025-11-05T15:02:35.171073642Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Nov 5 15:02:35.172025 containerd[1598]: time="2025-11-05T15:02:35.171986891Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:35.174684 containerd[1598]: time="2025-11-05T15:02:35.174644681Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:35.175845 containerd[1598]: time="2025-11-05T15:02:35.175818174Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 1.417004001s" Nov 5 15:02:35.175978 containerd[1598]: time="2025-11-05T15:02:35.175929549Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Nov 5 15:02:35.179614 containerd[1598]: time="2025-11-05T15:02:35.179583179Z" level=info msg="CreateContainer within sandbox \"6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 5 15:02:35.190604 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2239744005.mount: Deactivated successfully. Nov 5 15:02:35.191859 containerd[1598]: time="2025-11-05T15:02:35.191716452Z" level=info msg="Container f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:02:35.198588 containerd[1598]: time="2025-11-05T15:02:35.198552160Z" level=info msg="CreateContainer within sandbox \"6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0\"" Nov 5 15:02:35.199185 containerd[1598]: time="2025-11-05T15:02:35.199160267Z" level=info msg="StartContainer for \"f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0\"" Nov 5 15:02:35.200053 containerd[1598]: time="2025-11-05T15:02:35.200022362Z" level=info msg="connecting to shim f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0" address="unix:///run/containerd/s/5d279027c4ca9261c00ecd1291af0021908c717bde62acf1c260aa65cd500ad0" protocol=ttrpc version=3 Nov 5 15:02:35.227516 systemd[1]: Started cri-containerd-f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0.scope - libcontainer container f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0. Nov 5 15:02:35.256026 containerd[1598]: time="2025-11-05T15:02:35.255986481Z" level=info msg="StartContainer for \"f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0\" returns successfully" Nov 5 15:02:35.257733 systemd[1]: cri-containerd-f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0.scope: Deactivated successfully. Nov 5 15:02:35.261906 containerd[1598]: time="2025-11-05T15:02:35.261862102Z" level=info msg="TaskExit event in podsandbox handler container_id:\"f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0\" id:\"f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0\" pid:2230 exited_at:{seconds:1762354955 nanos:260917456}" Nov 5 15:02:35.262038 containerd[1598]: time="2025-11-05T15:02:35.262017900Z" level=info msg="received exit event container_id:\"f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0\" id:\"f05502392254ef808bc7856cbfc7e4fc022f3f5e5df6a3a2e6fca6b93b7243f0\" pid:2230 exited_at:{seconds:1762354955 nanos:260917456}" Nov 5 15:02:35.684573 kubelet[1887]: E1105 15:02:35.684507 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:35.842927 kubelet[1887]: E1105 15:02:35.842573 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:35.842927 kubelet[1887]: E1105 15:02:35.842645 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:35.844197 containerd[1598]: time="2025-11-05T15:02:35.843464507Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Nov 5 15:02:36.685554 kubelet[1887]: E1105 15:02:36.685505 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:37.413055 containerd[1598]: time="2025-11-05T15:02:37.412326490Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:37.413433 containerd[1598]: time="2025-11-05T15:02:37.413385969Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Nov 5 15:02:37.415518 containerd[1598]: time="2025-11-05T15:02:37.415470159Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:37.419026 containerd[1598]: time="2025-11-05T15:02:37.418985232Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:37.420518 containerd[1598]: time="2025-11-05T15:02:37.420476360Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 1.576974051s" Nov 5 15:02:37.420518 containerd[1598]: time="2025-11-05T15:02:37.420516684Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Nov 5 15:02:37.424401 containerd[1598]: time="2025-11-05T15:02:37.424300525Z" level=info msg="CreateContainer within sandbox \"6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 5 15:02:37.429933 containerd[1598]: time="2025-11-05T15:02:37.429887882Z" level=info msg="Container 7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:02:37.436528 containerd[1598]: time="2025-11-05T15:02:37.436491497Z" level=info msg="CreateContainer within sandbox \"6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76\"" Nov 5 15:02:37.437335 containerd[1598]: time="2025-11-05T15:02:37.437001412Z" level=info msg="StartContainer for \"7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76\"" Nov 5 15:02:37.438181 containerd[1598]: time="2025-11-05T15:02:37.438155468Z" level=info msg="connecting to shim 7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76" address="unix:///run/containerd/s/5d279027c4ca9261c00ecd1291af0021908c717bde62acf1c260aa65cd500ad0" protocol=ttrpc version=3 Nov 5 15:02:37.463506 systemd[1]: Started cri-containerd-7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76.scope - libcontainer container 7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76. Nov 5 15:02:37.488185 systemd[1]: cri-containerd-7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76.scope: Deactivated successfully. Nov 5 15:02:37.489565 containerd[1598]: time="2025-11-05T15:02:37.489526678Z" level=info msg="TaskExit event in podsandbox handler container_id:\"7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76\" id:\"7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76\" pid:2305 exited_at:{seconds:1762354957 nanos:489053395}" Nov 5 15:02:37.489859 containerd[1598]: time="2025-11-05T15:02:37.489837834Z" level=info msg="received exit event container_id:\"7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76\" id:\"7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76\" pid:2305 exited_at:{seconds:1762354957 nanos:489053395}" Nov 5 15:02:37.490620 containerd[1598]: time="2025-11-05T15:02:37.490596516Z" level=info msg="StartContainer for \"7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76\" returns successfully" Nov 5 15:02:37.507441 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-7c31c7743a4e34b001ec385f78d068b64fce2a5f961941a3387c7c7809098d76-rootfs.mount: Deactivated successfully. Nov 5 15:02:37.554518 kubelet[1887]: I1105 15:02:37.554485 1887 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 5 15:02:37.686365 kubelet[1887]: E1105 15:02:37.686218 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:37.847680 kubelet[1887]: E1105 15:02:37.847639 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:37.852764 containerd[1598]: time="2025-11-05T15:02:37.852727652Z" level=info msg="CreateContainer within sandbox \"6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 5 15:02:37.860233 containerd[1598]: time="2025-11-05T15:02:37.859603171Z" level=info msg="Container 45206408c7ee076e92ce26abb496b50334d865df898a6430d74b50e24b8027ae: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:02:37.866590 containerd[1598]: time="2025-11-05T15:02:37.866531516Z" level=info msg="CreateContainer within sandbox \"6234186338743a3c4262b61cfc74d1598d266370686f1964c223e1219eceb6b4\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"45206408c7ee076e92ce26abb496b50334d865df898a6430d74b50e24b8027ae\"" Nov 5 15:02:37.867091 containerd[1598]: time="2025-11-05T15:02:37.867054568Z" level=info msg="StartContainer for \"45206408c7ee076e92ce26abb496b50334d865df898a6430d74b50e24b8027ae\"" Nov 5 15:02:37.868012 containerd[1598]: time="2025-11-05T15:02:37.867952714Z" level=info msg="connecting to shim 45206408c7ee076e92ce26abb496b50334d865df898a6430d74b50e24b8027ae" address="unix:///run/containerd/s/5d279027c4ca9261c00ecd1291af0021908c717bde62acf1c260aa65cd500ad0" protocol=ttrpc version=3 Nov 5 15:02:37.883474 systemd[1]: Started cri-containerd-45206408c7ee076e92ce26abb496b50334d865df898a6430d74b50e24b8027ae.scope - libcontainer container 45206408c7ee076e92ce26abb496b50334d865df898a6430d74b50e24b8027ae. Nov 5 15:02:37.906919 containerd[1598]: time="2025-11-05T15:02:37.906884463Z" level=info msg="StartContainer for \"45206408c7ee076e92ce26abb496b50334d865df898a6430d74b50e24b8027ae\" returns successfully" Nov 5 15:02:38.687187 kubelet[1887]: E1105 15:02:38.687137 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:38.851858 kubelet[1887]: E1105 15:02:38.851777 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:38.869941 kubelet[1887]: I1105 15:02:38.869895 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-42sr6" podStartSLOduration=4.070868952 podStartE2EDuration="8.869878761s" podCreationTimestamp="2025-11-05 15:02:30 +0000 UTC" firstStartedPulling="2025-11-05 15:02:32.622549169 +0000 UTC m=+2.766229204" lastFinishedPulling="2025-11-05 15:02:37.421558978 +0000 UTC m=+7.565239013" observedRunningTime="2025-11-05 15:02:38.867365337 +0000 UTC m=+9.011045373" watchObservedRunningTime="2025-11-05 15:02:38.869878761 +0000 UTC m=+9.013558797" Nov 5 15:02:38.987163 systemd-networkd[1491]: flannel.1: Link UP Nov 5 15:02:38.987169 systemd-networkd[1491]: flannel.1: Gained carrier Nov 5 15:02:39.687417 kubelet[1887]: E1105 15:02:39.687372 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:39.853642 kubelet[1887]: E1105 15:02:39.853550 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Nov 5 15:02:40.210523 systemd-networkd[1491]: flannel.1: Gained IPv6LL Nov 5 15:02:40.687799 kubelet[1887]: E1105 15:02:40.687759 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:41.688050 kubelet[1887]: E1105 15:02:41.688005 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:42.235492 systemd[1]: Created slice kubepods-besteffort-pod8997f8c4_4484_4ead_ba58_da1405069a02.slice - libcontainer container kubepods-besteffort-pod8997f8c4_4484_4ead_ba58_da1405069a02.slice. Nov 5 15:02:42.253235 kubelet[1887]: I1105 15:02:42.253197 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x2kd6\" (UniqueName: \"kubernetes.io/projected/8997f8c4-4484-4ead-ba58-da1405069a02-kube-api-access-x2kd6\") pod \"nginx-deployment-7fcdb87857-t4drg\" (UID: \"8997f8c4-4484-4ead-ba58-da1405069a02\") " pod="default/nginx-deployment-7fcdb87857-t4drg" Nov 5 15:02:42.537975 containerd[1598]: time="2025-11-05T15:02:42.537866067Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-t4drg,Uid:8997f8c4-4484-4ead-ba58-da1405069a02,Namespace:default,Attempt:0,}" Nov 5 15:02:42.560444 systemd-networkd[1491]: cni0: Link UP Nov 5 15:02:42.560452 systemd-networkd[1491]: cni0: Gained carrier Nov 5 15:02:42.563012 systemd-networkd[1491]: cni0: Lost carrier Nov 5 15:02:42.572127 systemd-networkd[1491]: veth1941109d: Link UP Nov 5 15:02:42.574938 kernel: cni0: port 1(veth1941109d) entered blocking state Nov 5 15:02:42.575040 kernel: cni0: port 1(veth1941109d) entered disabled state Nov 5 15:02:42.575057 kernel: veth1941109d: entered allmulticast mode Nov 5 15:02:42.576337 kernel: veth1941109d: entered promiscuous mode Nov 5 15:02:42.583435 kernel: cni0: port 1(veth1941109d) entered blocking state Nov 5 15:02:42.583506 kernel: cni0: port 1(veth1941109d) entered forwarding state Nov 5 15:02:42.583652 systemd-networkd[1491]: veth1941109d: Gained carrier Nov 5 15:02:42.583897 systemd-networkd[1491]: cni0: Gained carrier Nov 5 15:02:42.586270 containerd[1598]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Nov 5 15:02:42.586270 containerd[1598]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:02:42.611867 containerd[1598]: {"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-05T15:02:42.611826979Z" level=info msg="connecting to shim 82f6c38d41244ad8a8e99a22ec8ee261ceff1d551f05a59ffc73c9d2b6f52d08" address="unix:///run/containerd/s/5a5488bd68bfa55b125c483ae7f4493e51464ea5b813ce17a9fb89e789256452" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:02:42.636490 systemd[1]: Started cri-containerd-82f6c38d41244ad8a8e99a22ec8ee261ceff1d551f05a59ffc73c9d2b6f52d08.scope - libcontainer container 82f6c38d41244ad8a8e99a22ec8ee261ceff1d551f05a59ffc73c9d2b6f52d08. Nov 5 15:02:42.648917 systemd-resolved[1279]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 5 15:02:42.668825 containerd[1598]: time="2025-11-05T15:02:42.668769585Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-t4drg,Uid:8997f8c4-4484-4ead-ba58-da1405069a02,Namespace:default,Attempt:0,} returns sandbox id \"82f6c38d41244ad8a8e99a22ec8ee261ceff1d551f05a59ffc73c9d2b6f52d08\"" Nov 5 15:02:42.670507 containerd[1598]: time="2025-11-05T15:02:42.670400401Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 15:02:42.688351 kubelet[1887]: E1105 15:02:42.688308 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:43.689216 kubelet[1887]: E1105 15:02:43.689160 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:43.731114 systemd-networkd[1491]: cni0: Gained IPv6LL Nov 5 15:02:43.987464 systemd-networkd[1491]: veth1941109d: Gained IPv6LL Nov 5 15:02:44.499429 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount296563511.mount: Deactivated successfully. Nov 5 15:02:44.689322 kubelet[1887]: E1105 15:02:44.689286 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:45.218566 containerd[1598]: time="2025-11-05T15:02:45.218517018Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:45.219082 containerd[1598]: time="2025-11-05T15:02:45.219051184Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 5 15:02:45.219877 containerd[1598]: time="2025-11-05T15:02:45.219825346Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:45.222272 containerd[1598]: time="2025-11-05T15:02:45.222238507Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:02:45.223897 containerd[1598]: time="2025-11-05T15:02:45.223858357Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 2.553413405s" Nov 5 15:02:45.223897 containerd[1598]: time="2025-11-05T15:02:45.223894181Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 15:02:45.226945 containerd[1598]: time="2025-11-05T15:02:45.226910403Z" level=info msg="CreateContainer within sandbox \"82f6c38d41244ad8a8e99a22ec8ee261ceff1d551f05a59ffc73c9d2b6f52d08\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 5 15:02:45.235048 containerd[1598]: time="2025-11-05T15:02:45.235014724Z" level=info msg="Container 2de278f2049294ec7dd8ffae3a1153dbae3e887c9d29b18708499ab5fb007381: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:02:45.237910 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3867815104.mount: Deactivated successfully. Nov 5 15:02:45.241854 containerd[1598]: time="2025-11-05T15:02:45.241801582Z" level=info msg="CreateContainer within sandbox \"82f6c38d41244ad8a8e99a22ec8ee261ceff1d551f05a59ffc73c9d2b6f52d08\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"2de278f2049294ec7dd8ffae3a1153dbae3e887c9d29b18708499ab5fb007381\"" Nov 5 15:02:45.243346 containerd[1598]: time="2025-11-05T15:02:45.242492447Z" level=info msg="StartContainer for \"2de278f2049294ec7dd8ffae3a1153dbae3e887c9d29b18708499ab5fb007381\"" Nov 5 15:02:45.243346 containerd[1598]: time="2025-11-05T15:02:45.243248458Z" level=info msg="connecting to shim 2de278f2049294ec7dd8ffae3a1153dbae3e887c9d29b18708499ab5fb007381" address="unix:///run/containerd/s/5a5488bd68bfa55b125c483ae7f4493e51464ea5b813ce17a9fb89e789256452" protocol=ttrpc version=3 Nov 5 15:02:45.262482 systemd[1]: Started cri-containerd-2de278f2049294ec7dd8ffae3a1153dbae3e887c9d29b18708499ab5fb007381.scope - libcontainer container 2de278f2049294ec7dd8ffae3a1153dbae3e887c9d29b18708499ab5fb007381. Nov 5 15:02:45.286259 containerd[1598]: time="2025-11-05T15:02:45.286221600Z" level=info msg="StartContainer for \"2de278f2049294ec7dd8ffae3a1153dbae3e887c9d29b18708499ab5fb007381\" returns successfully" Nov 5 15:02:45.690043 kubelet[1887]: E1105 15:02:45.690002 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:45.876136 kubelet[1887]: I1105 15:02:45.876081 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-t4drg" podStartSLOduration=1.321358956 podStartE2EDuration="3.876066544s" podCreationTimestamp="2025-11-05 15:02:42 +0000 UTC" firstStartedPulling="2025-11-05 15:02:42.669818138 +0000 UTC m=+12.813498134" lastFinishedPulling="2025-11-05 15:02:45.224525726 +0000 UTC m=+15.368205722" observedRunningTime="2025-11-05 15:02:45.875803411 +0000 UTC m=+16.019483446" watchObservedRunningTime="2025-11-05 15:02:45.876066544 +0000 UTC m=+16.019746580" Nov 5 15:02:46.690989 kubelet[1887]: E1105 15:02:46.690937 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:47.693638 kubelet[1887]: E1105 15:02:47.691421 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:48.671458 systemd[1]: Created slice kubepods-besteffort-pod95d4018c_90c4_41fe_becd_08b69337cde7.slice - libcontainer container kubepods-besteffort-pod95d4018c_90c4_41fe_becd_08b69337cde7.slice. Nov 5 15:02:48.681946 kubelet[1887]: I1105 15:02:48.681894 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/95d4018c-90c4-41fe-becd-08b69337cde7-data\") pod \"nfs-server-provisioner-0\" (UID: \"95d4018c-90c4-41fe-becd-08b69337cde7\") " pod="default/nfs-server-provisioner-0" Nov 5 15:02:48.681946 kubelet[1887]: I1105 15:02:48.681937 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xqggz\" (UniqueName: \"kubernetes.io/projected/95d4018c-90c4-41fe-becd-08b69337cde7-kube-api-access-xqggz\") pod \"nfs-server-provisioner-0\" (UID: \"95d4018c-90c4-41fe-becd-08b69337cde7\") " pod="default/nfs-server-provisioner-0" Nov 5 15:02:48.692075 kubelet[1887]: E1105 15:02:48.692033 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:48.975169 containerd[1598]: time="2025-11-05T15:02:48.974972861Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:95d4018c-90c4-41fe-becd-08b69337cde7,Namespace:default,Attempt:0,}" Nov 5 15:02:48.986590 systemd-networkd[1491]: vethe5da2d51: Link UP Nov 5 15:02:48.989364 kernel: cni0: port 2(vethe5da2d51) entered blocking state Nov 5 15:02:48.993824 kernel: cni0: port 2(vethe5da2d51) entered disabled state Nov 5 15:02:48.993921 kernel: vethe5da2d51: entered allmulticast mode Nov 5 15:02:48.997899 kernel: vethe5da2d51: entered promiscuous mode Nov 5 15:02:49.004419 kernel: cni0: port 2(vethe5da2d51) entered blocking state Nov 5 15:02:49.004482 kernel: cni0: port 2(vethe5da2d51) entered forwarding state Nov 5 15:02:49.004274 systemd-networkd[1491]: vethe5da2d51: Gained carrier Nov 5 15:02:49.006689 containerd[1598]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a2950), "name":"cbr0", "type":"bridge"} Nov 5 15:02:49.006689 containerd[1598]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:02:49.034477 containerd[1598]: {"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-05T15:02:49.034432299Z" level=info msg="connecting to shim 9faf321e42fe6c6842aa9623573551b83060a8e568bcd8110b84286a4bf3c5f9" address="unix:///run/containerd/s/b2e540a8c2e0cbd896a6df9559007d4c0569f0d61ecf4738d87f78295f602638" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:02:49.071556 systemd[1]: Started cri-containerd-9faf321e42fe6c6842aa9623573551b83060a8e568bcd8110b84286a4bf3c5f9.scope - libcontainer container 9faf321e42fe6c6842aa9623573551b83060a8e568bcd8110b84286a4bf3c5f9. Nov 5 15:02:49.083971 systemd-resolved[1279]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 5 15:02:49.106296 containerd[1598]: time="2025-11-05T15:02:49.106180706Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:95d4018c-90c4-41fe-becd-08b69337cde7,Namespace:default,Attempt:0,} returns sandbox id \"9faf321e42fe6c6842aa9623573551b83060a8e568bcd8110b84286a4bf3c5f9\"" Nov 5 15:02:49.107886 containerd[1598]: time="2025-11-05T15:02:49.107859706Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 5 15:02:49.693073 kubelet[1887]: E1105 15:02:49.693038 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:50.679786 kubelet[1887]: E1105 15:02:50.679737 1887 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:50.693284 kubelet[1887]: E1105 15:02:50.693248 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:50.770491 systemd-networkd[1491]: vethe5da2d51: Gained IPv6LL Nov 5 15:02:50.919828 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2695916604.mount: Deactivated successfully. Nov 5 15:02:51.693782 kubelet[1887]: E1105 15:02:51.693729 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:52.694200 kubelet[1887]: E1105 15:02:52.694149 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:53.694806 kubelet[1887]: E1105 15:02:53.694751 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:54.695826 kubelet[1887]: E1105 15:02:54.695770 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:55.696486 kubelet[1887]: E1105 15:02:55.696427 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:56.697640 kubelet[1887]: E1105 15:02:56.697592 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:57.698455 kubelet[1887]: E1105 15:02:57.698408 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:58.699230 kubelet[1887]: E1105 15:02:58.699180 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:02:59.699514 kubelet[1887]: E1105 15:02:59.699472 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:00.700515 kubelet[1887]: E1105 15:03:00.700477 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:01.700897 kubelet[1887]: E1105 15:03:01.700856 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:02.701711 kubelet[1887]: E1105 15:03:02.701663 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:03.701960 kubelet[1887]: E1105 15:03:03.701911 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:04.702339 kubelet[1887]: E1105 15:03:04.702273 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:05.702664 kubelet[1887]: E1105 15:03:05.702576 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:06.703010 kubelet[1887]: E1105 15:03:06.702947 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:07.703470 kubelet[1887]: E1105 15:03:07.703413 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:08.703613 kubelet[1887]: E1105 15:03:08.703563 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:08.869477 update_engine[1574]: I20251105 15:03:08.869390 1574 update_attempter.cc:509] Updating boot flags... Nov 5 15:03:09.704756 kubelet[1887]: E1105 15:03:09.704709 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:10.679448 kubelet[1887]: E1105 15:03:10.679394 1887 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:10.705277 kubelet[1887]: E1105 15:03:10.705225 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:11.705772 kubelet[1887]: E1105 15:03:11.705720 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:12.705923 kubelet[1887]: E1105 15:03:12.705859 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:13.706295 kubelet[1887]: E1105 15:03:13.706237 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:14.707074 kubelet[1887]: E1105 15:03:14.707034 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:15.708354 kubelet[1887]: E1105 15:03:15.708293 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:16.708784 kubelet[1887]: E1105 15:03:16.708732 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:17.709731 kubelet[1887]: E1105 15:03:17.709674 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:18.710472 kubelet[1887]: E1105 15:03:18.710420 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:19.711409 kubelet[1887]: E1105 15:03:19.711351 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:20.232728 containerd[1598]: time="2025-11-05T15:03:20.232679069Z" 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 5 15:03:20.234562 containerd[1598]: time="2025-11-05T15:03:20.234504242Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Nov 5 15:03:20.235511 containerd[1598]: time="2025-11-05T15:03:20.235457751Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:20.238829 containerd[1598]: time="2025-11-05T15:03:20.238767872Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:20.240492 containerd[1598]: time="2025-11-05T15:03:20.240436754Z" 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 31.132541527s" Nov 5 15:03:20.240492 containerd[1598]: time="2025-11-05T15:03:20.240477277Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 5 15:03:20.244351 containerd[1598]: time="2025-11-05T15:03:20.244068138Z" level=info msg="CreateContainer within sandbox \"9faf321e42fe6c6842aa9623573551b83060a8e568bcd8110b84286a4bf3c5f9\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 5 15:03:20.251790 containerd[1598]: time="2025-11-05T15:03:20.250587173Z" level=info msg="Container 0b0ead52221a76e527b3bb1bf39ec5bc8b8d1372e9da866e37922294f447b2bf: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:03:20.257894 containerd[1598]: time="2025-11-05T15:03:20.257837581Z" level=info msg="CreateContainer within sandbox \"9faf321e42fe6c6842aa9623573551b83060a8e568bcd8110b84286a4bf3c5f9\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"0b0ead52221a76e527b3bb1bf39ec5bc8b8d1372e9da866e37922294f447b2bf\"" Nov 5 15:03:20.258643 containerd[1598]: time="2025-11-05T15:03:20.258600916Z" level=info msg="StartContainer for \"0b0ead52221a76e527b3bb1bf39ec5bc8b8d1372e9da866e37922294f447b2bf\"" Nov 5 15:03:20.259666 containerd[1598]: time="2025-11-05T15:03:20.259621631Z" level=info msg="connecting to shim 0b0ead52221a76e527b3bb1bf39ec5bc8b8d1372e9da866e37922294f447b2bf" address="unix:///run/containerd/s/b2e540a8c2e0cbd896a6df9559007d4c0569f0d61ecf4738d87f78295f602638" protocol=ttrpc version=3 Nov 5 15:03:20.282520 systemd[1]: Started cri-containerd-0b0ead52221a76e527b3bb1bf39ec5bc8b8d1372e9da866e37922294f447b2bf.scope - libcontainer container 0b0ead52221a76e527b3bb1bf39ec5bc8b8d1372e9da866e37922294f447b2bf. Nov 5 15:03:20.307115 containerd[1598]: time="2025-11-05T15:03:20.307044124Z" level=info msg="StartContainer for \"0b0ead52221a76e527b3bb1bf39ec5bc8b8d1372e9da866e37922294f447b2bf\" returns successfully" Nov 5 15:03:20.712550 kubelet[1887]: E1105 15:03:20.712506 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:21.713054 kubelet[1887]: E1105 15:03:21.713007 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:22.714088 kubelet[1887]: E1105 15:03:22.714042 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:23.714452 kubelet[1887]: E1105 15:03:23.714383 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:24.715386 kubelet[1887]: E1105 15:03:24.715291 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:25.570133 kubelet[1887]: I1105 15:03:25.570069 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.436300244 podStartE2EDuration="37.570051058s" podCreationTimestamp="2025-11-05 15:02:48 +0000 UTC" firstStartedPulling="2025-11-05 15:02:49.107494999 +0000 UTC m=+19.251175035" lastFinishedPulling="2025-11-05 15:03:20.241245813 +0000 UTC m=+50.384925849" observedRunningTime="2025-11-05 15:03:20.952699895 +0000 UTC m=+51.096379931" watchObservedRunningTime="2025-11-05 15:03:25.570051058 +0000 UTC m=+55.713731094" Nov 5 15:03:25.577522 systemd[1]: Created slice kubepods-besteffort-pod7e8dcea9_ff38_4911_8c7e_555ff6b8100b.slice - libcontainer container kubepods-besteffort-pod7e8dcea9_ff38_4911_8c7e_555ff6b8100b.slice. Nov 5 15:03:25.715769 kubelet[1887]: E1105 15:03:25.715725 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:25.769094 kubelet[1887]: I1105 15:03:25.768983 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-cfe18b4c-0132-4cae-84d6-90a14d175564\" (UniqueName: \"kubernetes.io/nfs/7e8dcea9-ff38-4911-8c7e-555ff6b8100b-pvc-cfe18b4c-0132-4cae-84d6-90a14d175564\") pod \"test-pod-1\" (UID: \"7e8dcea9-ff38-4911-8c7e-555ff6b8100b\") " pod="default/test-pod-1" Nov 5 15:03:25.769094 kubelet[1887]: I1105 15:03:25.769045 1887 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6p9fq\" (UniqueName: \"kubernetes.io/projected/7e8dcea9-ff38-4911-8c7e-555ff6b8100b-kube-api-access-6p9fq\") pod \"test-pod-1\" (UID: \"7e8dcea9-ff38-4911-8c7e-555ff6b8100b\") " pod="default/test-pod-1" Nov 5 15:03:25.890367 kernel: netfs: FS-Cache loaded Nov 5 15:03:25.913771 kernel: RPC: Registered named UNIX socket transport module. Nov 5 15:03:25.913874 kernel: RPC: Registered udp transport module. Nov 5 15:03:25.913892 kernel: RPC: Registered tcp transport module. Nov 5 15:03:25.914952 kernel: RPC: Registered tcp-with-tls transport module. Nov 5 15:03:25.915036 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 5 15:03:26.088769 kernel: NFS: Registering the id_resolver key type Nov 5 15:03:26.088870 kernel: Key type id_resolver registered Nov 5 15:03:26.088891 kernel: Key type id_legacy registered Nov 5 15:03:26.107093 nfsidmap[3007]: 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 5 15:03:26.107946 nfsidmap[3007]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 5 15:03:26.110937 nfsidmap[3010]: 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 5 15:03:26.111088 nfsidmap[3010]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 5 15:03:26.116136 nfsrahead[3014]: setting /var/lib/kubelet/pods/7e8dcea9-ff38-4911-8c7e-555ff6b8100b/volumes/kubernetes.io~nfs/pvc-cfe18b4c-0132-4cae-84d6-90a14d175564 readahead to 128 Nov 5 15:03:26.181080 containerd[1598]: time="2025-11-05T15:03:26.180751791Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:7e8dcea9-ff38-4911-8c7e-555ff6b8100b,Namespace:default,Attempt:0,}" Nov 5 15:03:26.189668 systemd-networkd[1491]: vethdacbc22b: Link UP Nov 5 15:03:26.191865 kernel: cni0: port 3(vethdacbc22b) entered blocking state Nov 5 15:03:26.191922 kernel: cni0: port 3(vethdacbc22b) entered disabled state Nov 5 15:03:26.192677 kernel: vethdacbc22b: entered allmulticast mode Nov 5 15:03:26.193726 kernel: vethdacbc22b: entered promiscuous mode Nov 5 15:03:26.199343 kernel: cni0: port 3(vethdacbc22b) entered blocking state Nov 5 15:03:26.199404 kernel: cni0: port 3(vethdacbc22b) entered forwarding state Nov 5 15:03:26.199646 systemd-networkd[1491]: vethdacbc22b: Gained carrier Nov 5 15:03:26.200967 containerd[1598]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400009a950), "name":"cbr0", "type":"bridge"} Nov 5 15:03:26.200967 containerd[1598]: delegateAdd: netconf sent to delegate plugin: Nov 5 15:03:26.221516 containerd[1598]: {"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-05T15:03:26.221472516Z" level=info msg="connecting to shim d83d690c69e8feb934ec6c7df4aa2f7ee9e5566bb8cd650cfd296d387aeee48b" address="unix:///run/containerd/s/1476807fd65b5d13fc25739b720ff93c446ab97f9892556254f80f577467408b" namespace=k8s.io protocol=ttrpc version=3 Nov 5 15:03:26.244514 systemd[1]: Started cri-containerd-d83d690c69e8feb934ec6c7df4aa2f7ee9e5566bb8cd650cfd296d387aeee48b.scope - libcontainer container d83d690c69e8feb934ec6c7df4aa2f7ee9e5566bb8cd650cfd296d387aeee48b. Nov 5 15:03:26.254458 systemd-resolved[1279]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 5 15:03:26.273087 containerd[1598]: time="2025-11-05T15:03:26.273049073Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:7e8dcea9-ff38-4911-8c7e-555ff6b8100b,Namespace:default,Attempt:0,} returns sandbox id \"d83d690c69e8feb934ec6c7df4aa2f7ee9e5566bb8cd650cfd296d387aeee48b\"" Nov 5 15:03:26.274150 containerd[1598]: time="2025-11-05T15:03:26.274121855Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 15:03:26.619364 containerd[1598]: time="2025-11-05T15:03:26.619207022Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 15:03:26.619792 containerd[1598]: time="2025-11-05T15:03:26.619756014Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 5 15:03:26.622371 containerd[1598]: time="2025-11-05T15:03:26.622325243Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 348.157786ms" Nov 5 15:03:26.622371 containerd[1598]: time="2025-11-05T15:03:26.622359605Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 15:03:26.625270 containerd[1598]: time="2025-11-05T15:03:26.625236293Z" level=info msg="CreateContainer within sandbox \"d83d690c69e8feb934ec6c7df4aa2f7ee9e5566bb8cd650cfd296d387aeee48b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 5 15:03:26.632114 containerd[1598]: time="2025-11-05T15:03:26.632059889Z" level=info msg="Container c6351314ab6494266bc3ae8d5aa8c09fd2a64ec823acf890c9f5835dd47dcf02: CDI devices from CRI Config.CDIDevices: []" Nov 5 15:03:26.637377 containerd[1598]: time="2025-11-05T15:03:26.637344316Z" level=info msg="CreateContainer within sandbox \"d83d690c69e8feb934ec6c7df4aa2f7ee9e5566bb8cd650cfd296d387aeee48b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"c6351314ab6494266bc3ae8d5aa8c09fd2a64ec823acf890c9f5835dd47dcf02\"" Nov 5 15:03:26.637981 containerd[1598]: time="2025-11-05T15:03:26.637954111Z" level=info msg="StartContainer for \"c6351314ab6494266bc3ae8d5aa8c09fd2a64ec823acf890c9f5835dd47dcf02\"" Nov 5 15:03:26.639273 containerd[1598]: time="2025-11-05T15:03:26.638979731Z" level=info msg="connecting to shim c6351314ab6494266bc3ae8d5aa8c09fd2a64ec823acf890c9f5835dd47dcf02" address="unix:///run/containerd/s/1476807fd65b5d13fc25739b720ff93c446ab97f9892556254f80f577467408b" protocol=ttrpc version=3 Nov 5 15:03:26.663524 systemd[1]: Started cri-containerd-c6351314ab6494266bc3ae8d5aa8c09fd2a64ec823acf890c9f5835dd47dcf02.scope - libcontainer container c6351314ab6494266bc3ae8d5aa8c09fd2a64ec823acf890c9f5835dd47dcf02. Nov 5 15:03:26.689674 containerd[1598]: time="2025-11-05T15:03:26.689610512Z" level=info msg="StartContainer for \"c6351314ab6494266bc3ae8d5aa8c09fd2a64ec823acf890c9f5835dd47dcf02\" returns successfully" Nov 5 15:03:26.716458 kubelet[1887]: E1105 15:03:26.716415 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:27.634486 systemd-networkd[1491]: vethdacbc22b: Gained IPv6LL Nov 5 15:03:27.717420 kubelet[1887]: E1105 15:03:27.717367 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:28.717544 kubelet[1887]: E1105 15:03:28.717493 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:29.718675 kubelet[1887]: E1105 15:03:29.718599 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:30.679891 kubelet[1887]: E1105 15:03:30.679837 1887 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:30.718790 kubelet[1887]: E1105 15:03:30.718733 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:31.719467 kubelet[1887]: E1105 15:03:31.719419 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 15:03:32.720249 kubelet[1887]: E1105 15:03:32.720202 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"