Oct 31 23:40:25.321667 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 31 23:40:25.321691 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 Fri Oct 31 22:16:56 -00 2025 Oct 31 23:40:25.321699 kernel: KASLR enabled Oct 31 23:40:25.321705 kernel: efi: EFI v2.7 by EDK II Oct 31 23:40:25.321711 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Oct 31 23:40:25.321717 kernel: random: crng init done Oct 31 23:40:25.321724 kernel: secureboot: Secure boot disabled Oct 31 23:40:25.321729 kernel: ACPI: Early table checksum verification disabled Oct 31 23:40:25.321737 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 31 23:40:25.321743 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 31 23:40:25.321749 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321755 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321761 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321768 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321776 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321783 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321789 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321796 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321802 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 31 23:40:25.321808 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 31 23:40:25.321815 kernel: ACPI: Use ACPI SPCR as default console: No Oct 31 23:40:25.321821 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 23:40:25.321829 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Oct 31 23:40:25.321835 kernel: Zone ranges: Oct 31 23:40:25.321841 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 23:40:25.321848 kernel: DMA32 empty Oct 31 23:40:25.321854 kernel: Normal empty Oct 31 23:40:25.321860 kernel: Device empty Oct 31 23:40:25.321866 kernel: Movable zone start for each node Oct 31 23:40:25.321873 kernel: Early memory node ranges Oct 31 23:40:25.321945 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Oct 31 23:40:25.321954 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Oct 31 23:40:25.321961 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Oct 31 23:40:25.321967 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 31 23:40:25.321976 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 31 23:40:25.321982 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 31 23:40:25.321989 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 31 23:40:25.321995 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 31 23:40:25.322001 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 31 23:40:25.322008 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 31 23:40:25.322018 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 31 23:40:25.322025 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 31 23:40:25.322031 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 31 23:40:25.322038 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 31 23:40:25.322045 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 31 23:40:25.322052 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Oct 31 23:40:25.322058 kernel: psci: probing for conduit method from ACPI. Oct 31 23:40:25.322071 kernel: psci: PSCIv1.1 detected in firmware. Oct 31 23:40:25.322080 kernel: psci: Using standard PSCI v0.2 function IDs Oct 31 23:40:25.322087 kernel: psci: Trusted OS migration not required Oct 31 23:40:25.322094 kernel: psci: SMC Calling Convention v1.1 Oct 31 23:40:25.322100 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 31 23:40:25.322107 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 31 23:40:25.322114 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 31 23:40:25.322121 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 31 23:40:25.322128 kernel: Detected PIPT I-cache on CPU0 Oct 31 23:40:25.322135 kernel: CPU features: detected: GIC system register CPU interface Oct 31 23:40:25.322141 kernel: CPU features: detected: Spectre-v4 Oct 31 23:40:25.322148 kernel: CPU features: detected: Spectre-BHB Oct 31 23:40:25.322156 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 31 23:40:25.322163 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 31 23:40:25.322170 kernel: CPU features: detected: ARM erratum 1418040 Oct 31 23:40:25.322177 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 31 23:40:25.322183 kernel: alternatives: applying boot alternatives Oct 31 23:40:25.322191 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=15f20dc53039b70782f7f2390e499f4daf68c2143d2132ed7885b2fd0f57c516 Oct 31 23:40:25.322198 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 31 23:40:25.322205 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 31 23:40:25.322212 kernel: Fallback order for Node 0: 0 Oct 31 23:40:25.322219 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 31 23:40:25.322227 kernel: Policy zone: DMA Oct 31 23:40:25.322234 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 31 23:40:25.322241 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 31 23:40:25.322248 kernel: software IO TLB: area num 4. Oct 31 23:40:25.322298 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 31 23:40:25.322306 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Oct 31 23:40:25.322313 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 31 23:40:25.322319 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 31 23:40:25.322326 kernel: rcu: RCU event tracing is enabled. Oct 31 23:40:25.322333 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 31 23:40:25.322340 kernel: Trampoline variant of Tasks RCU enabled. Oct 31 23:40:25.322352 kernel: Tracing variant of Tasks RCU enabled. Oct 31 23:40:25.322360 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 31 23:40:25.322367 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 31 23:40:25.322374 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 31 23:40:25.322381 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 31 23:40:25.322388 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 31 23:40:25.322395 kernel: GICv3: 256 SPIs implemented Oct 31 23:40:25.322402 kernel: GICv3: 0 Extended SPIs implemented Oct 31 23:40:25.322408 kernel: Root IRQ handler: gic_handle_irq Oct 31 23:40:25.322415 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 31 23:40:25.322422 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 31 23:40:25.322431 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 31 23:40:25.322437 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 31 23:40:25.322444 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 31 23:40:25.322451 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 31 23:40:25.322458 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 31 23:40:25.322465 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 31 23:40:25.322472 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 31 23:40:25.322479 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 23:40:25.322485 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 31 23:40:25.322493 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 31 23:40:25.322499 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 31 23:40:25.322508 kernel: arm-pv: using stolen time PV Oct 31 23:40:25.322515 kernel: Console: colour dummy device 80x25 Oct 31 23:40:25.322522 kernel: ACPI: Core revision 20240827 Oct 31 23:40:25.322530 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 31 23:40:25.322537 kernel: pid_max: default: 32768 minimum: 301 Oct 31 23:40:25.322544 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 31 23:40:25.322551 kernel: landlock: Up and running. Oct 31 23:40:25.322558 kernel: SELinux: Initializing. Oct 31 23:40:25.322567 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 23:40:25.322574 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 31 23:40:25.322581 kernel: rcu: Hierarchical SRCU implementation. Oct 31 23:40:25.322589 kernel: rcu: Max phase no-delay instances is 400. Oct 31 23:40:25.322596 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 31 23:40:25.322603 kernel: Remapping and enabling EFI services. Oct 31 23:40:25.322610 kernel: smp: Bringing up secondary CPUs ... Oct 31 23:40:25.322619 kernel: Detected PIPT I-cache on CPU1 Oct 31 23:40:25.322631 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 31 23:40:25.322639 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 31 23:40:25.322647 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 23:40:25.322654 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 31 23:40:25.322662 kernel: Detected PIPT I-cache on CPU2 Oct 31 23:40:25.322669 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 31 23:40:25.322678 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 31 23:40:25.322686 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 23:40:25.322693 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 31 23:40:25.322700 kernel: Detected PIPT I-cache on CPU3 Oct 31 23:40:25.322708 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 31 23:40:25.322716 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 31 23:40:25.322723 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 31 23:40:25.322732 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 31 23:40:25.322739 kernel: smp: Brought up 1 node, 4 CPUs Oct 31 23:40:25.322747 kernel: SMP: Total of 4 processors activated. Oct 31 23:40:25.322754 kernel: CPU: All CPU(s) started at EL1 Oct 31 23:40:25.322762 kernel: CPU features: detected: 32-bit EL0 Support Oct 31 23:40:25.322770 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 31 23:40:25.322777 kernel: CPU features: detected: Common not Private translations Oct 31 23:40:25.322786 kernel: CPU features: detected: CRC32 instructions Oct 31 23:40:25.322793 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 31 23:40:25.322800 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 31 23:40:25.322808 kernel: CPU features: detected: LSE atomic instructions Oct 31 23:40:25.322815 kernel: CPU features: detected: Privileged Access Never Oct 31 23:40:25.322823 kernel: CPU features: detected: RAS Extension Support Oct 31 23:40:25.322830 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 31 23:40:25.322839 kernel: alternatives: applying system-wide alternatives Oct 31 23:40:25.322846 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 31 23:40:25.322854 kernel: Memory: 2450400K/2572288K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 99552K reserved, 16384K cma-reserved) Oct 31 23:40:25.322862 kernel: devtmpfs: initialized Oct 31 23:40:25.322870 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 31 23:40:25.322877 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 31 23:40:25.322884 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 31 23:40:25.322893 kernel: 0 pages in range for non-PLT usage Oct 31 23:40:25.322900 kernel: 515056 pages in range for PLT usage Oct 31 23:40:25.322908 kernel: pinctrl core: initialized pinctrl subsystem Oct 31 23:40:25.322915 kernel: SMBIOS 3.0.0 present. Oct 31 23:40:25.322923 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 31 23:40:25.322930 kernel: DMI: Memory slots populated: 1/1 Oct 31 23:40:25.322938 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 31 23:40:25.322945 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 31 23:40:25.322954 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 31 23:40:25.322961 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 31 23:40:25.322969 kernel: audit: initializing netlink subsys (disabled) Oct 31 23:40:25.322977 kernel: audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 Oct 31 23:40:25.322984 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 31 23:40:25.322992 kernel: cpuidle: using governor menu Oct 31 23:40:25.322999 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 31 23:40:25.323008 kernel: ASID allocator initialised with 32768 entries Oct 31 23:40:25.323016 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 31 23:40:25.323023 kernel: Serial: AMBA PL011 UART driver Oct 31 23:40:25.323031 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 31 23:40:25.323038 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 31 23:40:25.323046 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 31 23:40:25.323053 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 31 23:40:25.323069 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 31 23:40:25.323077 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 31 23:40:25.323085 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 31 23:40:25.323092 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 31 23:40:25.323100 kernel: ACPI: Added _OSI(Module Device) Oct 31 23:40:25.323107 kernel: ACPI: Added _OSI(Processor Device) Oct 31 23:40:25.323114 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 31 23:40:25.323122 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 31 23:40:25.323131 kernel: ACPI: Interpreter enabled Oct 31 23:40:25.323139 kernel: ACPI: Using GIC for interrupt routing Oct 31 23:40:25.323146 kernel: ACPI: MCFG table detected, 1 entries Oct 31 23:40:25.323154 kernel: ACPI: CPU0 has been hot-added Oct 31 23:40:25.323161 kernel: ACPI: CPU1 has been hot-added Oct 31 23:40:25.323168 kernel: ACPI: CPU2 has been hot-added Oct 31 23:40:25.323176 kernel: ACPI: CPU3 has been hot-added Oct 31 23:40:25.323185 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 31 23:40:25.323192 kernel: printk: legacy console [ttyAMA0] enabled Oct 31 23:40:25.323200 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 31 23:40:25.323370 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 31 23:40:25.323462 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 31 23:40:25.323541 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 31 23:40:25.323622 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 31 23:40:25.323710 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 31 23:40:25.323720 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 31 23:40:25.323728 kernel: PCI host bridge to bus 0000:00 Oct 31 23:40:25.323816 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 31 23:40:25.323889 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 31 23:40:25.324010 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 31 23:40:25.324093 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 31 23:40:25.324190 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 31 23:40:25.324312 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 31 23:40:25.324405 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 31 23:40:25.324487 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 31 23:40:25.324565 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 31 23:40:25.324643 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 31 23:40:25.324722 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 31 23:40:25.324800 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 31 23:40:25.324871 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 31 23:40:25.324943 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 31 23:40:25.325014 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 31 23:40:25.325023 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 31 23:40:25.325031 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 31 23:40:25.325039 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 31 23:40:25.325046 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 31 23:40:25.325054 kernel: iommu: Default domain type: Translated Oct 31 23:40:25.325072 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 31 23:40:25.325081 kernel: efivars: Registered efivars operations Oct 31 23:40:25.325088 kernel: vgaarb: loaded Oct 31 23:40:25.325095 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 31 23:40:25.325103 kernel: VFS: Disk quotas dquot_6.6.0 Oct 31 23:40:25.325110 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 31 23:40:25.325118 kernel: pnp: PnP ACPI init Oct 31 23:40:25.325232 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 31 23:40:25.325243 kernel: pnp: PnP ACPI: found 1 devices Oct 31 23:40:25.325271 kernel: NET: Registered PF_INET protocol family Oct 31 23:40:25.325281 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 31 23:40:25.325289 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 31 23:40:25.325297 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 31 23:40:25.325319 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 31 23:40:25.325330 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 31 23:40:25.325338 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 31 23:40:25.325346 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 23:40:25.325353 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 31 23:40:25.325361 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 31 23:40:25.325368 kernel: PCI: CLS 0 bytes, default 64 Oct 31 23:40:25.325376 kernel: kvm [1]: HYP mode not available Oct 31 23:40:25.325385 kernel: Initialise system trusted keyrings Oct 31 23:40:25.325393 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 31 23:40:25.325400 kernel: Key type asymmetric registered Oct 31 23:40:25.325408 kernel: Asymmetric key parser 'x509' registered Oct 31 23:40:25.325415 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 31 23:40:25.325423 kernel: io scheduler mq-deadline registered Oct 31 23:40:25.325431 kernel: io scheduler kyber registered Oct 31 23:40:25.325439 kernel: io scheduler bfq registered Oct 31 23:40:25.325447 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 31 23:40:25.325454 kernel: ACPI: button: Power Button [PWRB] Oct 31 23:40:25.325462 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 31 23:40:25.325555 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 31 23:40:25.325565 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 31 23:40:25.325573 kernel: thunder_xcv, ver 1.0 Oct 31 23:40:25.325582 kernel: thunder_bgx, ver 1.0 Oct 31 23:40:25.325590 kernel: nicpf, ver 1.0 Oct 31 23:40:25.325597 kernel: nicvf, ver 1.0 Oct 31 23:40:25.325688 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 31 23:40:25.325764 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-31T23:40:24 UTC (1761954024) Oct 31 23:40:25.325774 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 31 23:40:25.325784 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 31 23:40:25.325791 kernel: watchdog: NMI not fully supported Oct 31 23:40:25.325799 kernel: watchdog: Hard watchdog permanently disabled Oct 31 23:40:25.325806 kernel: NET: Registered PF_INET6 protocol family Oct 31 23:40:25.325814 kernel: Segment Routing with IPv6 Oct 31 23:40:25.325822 kernel: In-situ OAM (IOAM) with IPv6 Oct 31 23:40:25.325829 kernel: NET: Registered PF_PACKET protocol family Oct 31 23:40:25.325837 kernel: Key type dns_resolver registered Oct 31 23:40:25.325845 kernel: registered taskstats version 1 Oct 31 23:40:25.325854 kernel: Loading compiled-in X.509 certificates Oct 31 23:40:25.325867 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: c9d24981cd4969567cd1cd972bfbf6b879d6e529' Oct 31 23:40:25.325875 kernel: Demotion targets for Node 0: null Oct 31 23:40:25.325883 kernel: Key type .fscrypt registered Oct 31 23:40:25.325891 kernel: Key type fscrypt-provisioning registered Oct 31 23:40:25.325899 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 31 23:40:25.325907 kernel: ima: Allocated hash algorithm: sha1 Oct 31 23:40:25.325915 kernel: ima: No architecture policies found Oct 31 23:40:25.325923 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 31 23:40:25.325930 kernel: clk: Disabling unused clocks Oct 31 23:40:25.325938 kernel: PM: genpd: Disabling unused power domains Oct 31 23:40:25.325946 kernel: Freeing unused kernel memory: 12992K Oct 31 23:40:25.325953 kernel: Run /init as init process Oct 31 23:40:25.325962 kernel: with arguments: Oct 31 23:40:25.325969 kernel: /init Oct 31 23:40:25.325977 kernel: with environment: Oct 31 23:40:25.325984 kernel: HOME=/ Oct 31 23:40:25.325992 kernel: TERM=linux Oct 31 23:40:25.326095 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 31 23:40:25.326179 kernel: virtio_blk virtio1: [vda] 27000832 512-byte logical blocks (13.8 GB/12.9 GiB) Oct 31 23:40:25.326192 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 31 23:40:25.326200 kernel: GPT:16515071 != 27000831 Oct 31 23:40:25.326207 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 31 23:40:25.326215 kernel: GPT:16515071 != 27000831 Oct 31 23:40:25.326222 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 31 23:40:25.326229 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 31 23:40:25.326238 kernel: SCSI subsystem initialized Oct 31 23:40:25.326246 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 31 23:40:25.326264 kernel: device-mapper: uevent: version 1.0.3 Oct 31 23:40:25.326272 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 31 23:40:25.326516 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 31 23:40:25.326540 kernel: raid6: neonx8 gen() 15750 MB/s Oct 31 23:40:25.326548 kernel: raid6: neonx4 gen() 15782 MB/s Oct 31 23:40:25.326559 kernel: raid6: neonx2 gen() 12890 MB/s Oct 31 23:40:25.326567 kernel: raid6: neonx1 gen() 10410 MB/s Oct 31 23:40:25.326575 kernel: raid6: int64x8 gen() 6897 MB/s Oct 31 23:40:25.326582 kernel: raid6: int64x4 gen() 7275 MB/s Oct 31 23:40:25.326590 kernel: raid6: int64x2 gen() 6104 MB/s Oct 31 23:40:25.326597 kernel: raid6: int64x1 gen() 5008 MB/s Oct 31 23:40:25.326605 kernel: raid6: using algorithm neonx4 gen() 15782 MB/s Oct 31 23:40:25.326614 kernel: raid6: .... xor() 12358 MB/s, rmw enabled Oct 31 23:40:25.326621 kernel: raid6: using neon recovery algorithm Oct 31 23:40:25.326629 kernel: xor: measuring software checksum speed Oct 31 23:40:25.326637 kernel: 8regs : 18894 MB/sec Oct 31 23:40:25.326644 kernel: 32regs : 21681 MB/sec Oct 31 23:40:25.326652 kernel: arm64_neon : 28013 MB/sec Oct 31 23:40:25.326659 kernel: xor: using function: arm64_neon (28013 MB/sec) Oct 31 23:40:25.326668 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 31 23:40:25.326676 kernel: BTRFS: device fsid 3654cc92-f986-406b-ab0a-b6dce1e6a3a6 devid 1 transid 36 /dev/mapper/usr (253:0) scanned by mount (204) Oct 31 23:40:25.326684 kernel: BTRFS info (device dm-0): first mount of filesystem 3654cc92-f986-406b-ab0a-b6dce1e6a3a6 Oct 31 23:40:25.326692 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 31 23:40:25.326700 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 31 23:40:25.326708 kernel: BTRFS info (device dm-0): enabling free space tree Oct 31 23:40:25.326716 kernel: loop: module loaded Oct 31 23:40:25.326725 kernel: loop0: detected capacity change from 0 to 91464 Oct 31 23:40:25.326732 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 31 23:40:25.326741 systemd[1]: Successfully made /usr/ read-only. Oct 31 23:40:25.326752 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) Oct 31 23:40:25.326760 systemd[1]: Detected virtualization kvm. Oct 31 23:40:25.326768 systemd[1]: Detected architecture arm64. Oct 31 23:40:25.326777 systemd[1]: Running in initrd. Oct 31 23:40:25.326786 systemd[1]: No hostname configured, using default hostname. Oct 31 23:40:25.326794 systemd[1]: Hostname set to . Oct 31 23:40:25.326802 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 31 23:40:25.326810 systemd[1]: Queued start job for default target initrd.target. Oct 31 23:40:25.326821 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 31 23:40:25.326830 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 23:40:25.326840 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 23:40:25.326849 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 31 23:40:25.326857 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 23:40:25.326866 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 31 23:40:25.326875 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 31 23:40:25.326885 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 23:40:25.326893 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 23:40:25.326901 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 31 23:40:25.326909 systemd[1]: Reached target paths.target - Path Units. Oct 31 23:40:25.326917 systemd[1]: Reached target slices.target - Slice Units. Oct 31 23:40:25.326925 systemd[1]: Reached target swap.target - Swaps. Oct 31 23:40:25.326933 systemd[1]: Reached target timers.target - Timer Units. Oct 31 23:40:25.326943 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 23:40:25.326951 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 23:40:25.326959 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 31 23:40:25.326967 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 31 23:40:25.326983 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 23:40:25.326993 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 23:40:25.327002 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 23:40:25.327010 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 23:40:25.327019 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 31 23:40:25.327028 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 31 23:40:25.327036 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 23:40:25.327045 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 31 23:40:25.327055 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 31 23:40:25.327072 systemd[1]: Starting systemd-fsck-usr.service... Oct 31 23:40:25.327081 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 23:40:25.327089 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 23:40:25.327097 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 23:40:25.327108 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 31 23:40:25.327117 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 23:40:25.327125 systemd[1]: Finished systemd-fsck-usr.service. Oct 31 23:40:25.327134 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 31 23:40:25.327165 systemd-journald[343]: Collecting audit messages is disabled. Oct 31 23:40:25.327187 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 31 23:40:25.327195 kernel: Bridge firewalling registered Oct 31 23:40:25.327203 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 23:40:25.327214 systemd-journald[343]: Journal started Oct 31 23:40:25.327232 systemd-journald[343]: Runtime Journal (/run/log/journal/a9f5e2cc0f50470384d13daab048c976) is 6M, max 48.5M, 42.4M free. Oct 31 23:40:25.325814 systemd-modules-load[344]: Inserted module 'br_netfilter' Oct 31 23:40:25.332276 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 23:40:25.333412 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 23:40:25.336107 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 23:40:25.339924 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 31 23:40:25.341620 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 23:40:25.343696 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 23:40:25.351782 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 23:40:25.360098 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 23:40:25.362387 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 23:40:25.365005 systemd-tmpfiles[368]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 31 23:40:25.365045 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 23:40:25.369924 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 23:40:25.377422 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 23:40:25.379508 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 31 23:40:25.393446 dracut-cmdline[389]: 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=15f20dc53039b70782f7f2390e499f4daf68c2143d2132ed7885b2fd0f57c516 Oct 31 23:40:25.415136 systemd-resolved[381]: Positive Trust Anchors: Oct 31 23:40:25.415150 systemd-resolved[381]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 23:40:25.415154 systemd-resolved[381]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 31 23:40:25.415185 systemd-resolved[381]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 31 23:40:25.437251 systemd-resolved[381]: Defaulting to hostname 'linux'. Oct 31 23:40:25.438139 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 23:40:25.439385 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 23:40:25.469287 kernel: Loading iSCSI transport class v2.0-870. Oct 31 23:40:25.477268 kernel: iscsi: registered transport (tcp) Oct 31 23:40:25.490704 kernel: iscsi: registered transport (qla4xxx) Oct 31 23:40:25.490745 kernel: QLogic iSCSI HBA Driver Oct 31 23:40:25.509966 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 31 23:40:25.523922 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 31 23:40:25.525535 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 31 23:40:25.568625 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 31 23:40:25.570951 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 31 23:40:25.572640 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 31 23:40:25.606469 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 31 23:40:25.609531 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 23:40:25.639801 systemd-udevd[626]: Using default interface naming scheme 'v257'. Oct 31 23:40:25.647614 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 23:40:25.651554 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 31 23:40:25.673497 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 23:40:25.676773 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 23:40:25.681643 dracut-pre-trigger[700]: rd.md=0: removing MD RAID activation Oct 31 23:40:25.702243 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 23:40:25.704866 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 23:40:25.714902 systemd-networkd[735]: lo: Link UP Oct 31 23:40:25.714910 systemd-networkd[735]: lo: Gained carrier Oct 31 23:40:25.715335 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 23:40:25.716728 systemd[1]: Reached target network.target - Network. Oct 31 23:40:25.754428 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 23:40:25.757478 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 31 23:40:25.808117 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 31 23:40:25.815682 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 31 23:40:25.822128 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 31 23:40:25.829685 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 31 23:40:25.833405 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 31 23:40:25.842814 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 23:40:25.842931 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 23:40:25.847741 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 23:40:25.851471 systemd-networkd[735]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 31 23:40:25.851478 systemd-networkd[735]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 23:40:25.851748 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 23:40:25.865548 disk-uuid[804]: Primary Header is updated. Oct 31 23:40:25.865548 disk-uuid[804]: Secondary Entries is updated. Oct 31 23:40:25.865548 disk-uuid[804]: Secondary Header is updated. Oct 31 23:40:25.854421 systemd-networkd[735]: eth0: Link UP Oct 31 23:40:25.854621 systemd-networkd[735]: eth0: Gained carrier Oct 31 23:40:25.854632 systemd-networkd[735]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 31 23:40:25.869971 systemd-networkd[735]: eth0: DHCPv4 address 10.0.0.49/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 31 23:40:25.886369 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 23:40:25.918182 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 31 23:40:25.919825 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 23:40:25.921514 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 23:40:25.923576 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 23:40:25.926397 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 31 23:40:25.951315 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 31 23:40:26.130484 systemd-resolved[381]: Detected conflict on linux IN A 10.0.0.49 Oct 31 23:40:26.130502 systemd-resolved[381]: Hostname conflict, changing published hostname from 'linux' to 'linux3'. Oct 31 23:40:26.880690 disk-uuid[806]: Warning: The kernel is still using the old partition table. Oct 31 23:40:26.880690 disk-uuid[806]: The new table will be used at the next reboot or after you Oct 31 23:40:26.880690 disk-uuid[806]: run partprobe(8) or kpartx(8) Oct 31 23:40:26.880690 disk-uuid[806]: The operation has completed successfully. Oct 31 23:40:26.885781 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 31 23:40:26.885884 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 31 23:40:26.891115 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 31 23:40:26.920815 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (836) Oct 31 23:40:26.920854 kernel: BTRFS info (device vda6): first mount of filesystem c6954884-bcd5-4df1-a2fb-0b060c87e5c9 Oct 31 23:40:26.920866 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 31 23:40:26.924571 kernel: BTRFS info (device vda6): turning on async discard Oct 31 23:40:26.924605 kernel: BTRFS info (device vda6): enabling free space tree Oct 31 23:40:26.930274 kernel: BTRFS info (device vda6): last unmount of filesystem c6954884-bcd5-4df1-a2fb-0b060c87e5c9 Oct 31 23:40:26.930704 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 31 23:40:26.932753 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 31 23:40:27.040049 ignition[855]: Ignition 2.22.0 Oct 31 23:40:27.040071 ignition[855]: Stage: fetch-offline Oct 31 23:40:27.040110 ignition[855]: no configs at "/usr/lib/ignition/base.d" Oct 31 23:40:27.040120 ignition[855]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 23:40:27.040201 ignition[855]: parsed url from cmdline: "" Oct 31 23:40:27.040204 ignition[855]: no config URL provided Oct 31 23:40:27.040208 ignition[855]: reading system config file "/usr/lib/ignition/user.ign" Oct 31 23:40:27.040216 ignition[855]: no config at "/usr/lib/ignition/user.ign" Oct 31 23:40:27.040272 ignition[855]: op(1): [started] loading QEMU firmware config module Oct 31 23:40:27.040280 ignition[855]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 31 23:40:27.050331 ignition[855]: op(1): [finished] loading QEMU firmware config module Oct 31 23:40:27.056950 ignition[855]: parsing config with SHA512: 32c915571e518307b7818ee92508aa0ea3305d96285ff631963c59fe28e766b15a29fa97dc57267b0167c52b4f40087a6d65d598c813f03e192e03ea4068c30e Oct 31 23:40:27.060812 unknown[855]: fetched base config from "system" Oct 31 23:40:27.060824 unknown[855]: fetched user config from "qemu" Oct 31 23:40:27.061133 ignition[855]: fetch-offline: fetch-offline passed Oct 31 23:40:27.061201 ignition[855]: Ignition finished successfully Oct 31 23:40:27.063868 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 23:40:27.066578 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 31 23:40:27.067361 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 31 23:40:27.095718 ignition[871]: Ignition 2.22.0 Oct 31 23:40:27.095736 ignition[871]: Stage: kargs Oct 31 23:40:27.095867 ignition[871]: no configs at "/usr/lib/ignition/base.d" Oct 31 23:40:27.095875 ignition[871]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 23:40:27.098652 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 31 23:40:27.096471 ignition[871]: kargs: kargs passed Oct 31 23:40:27.101233 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 31 23:40:27.096511 ignition[871]: Ignition finished successfully Oct 31 23:40:27.129449 ignition[880]: Ignition 2.22.0 Oct 31 23:40:27.129465 ignition[880]: Stage: disks Oct 31 23:40:27.129594 ignition[880]: no configs at "/usr/lib/ignition/base.d" Oct 31 23:40:27.131916 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 31 23:40:27.129602 ignition[880]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 23:40:27.133283 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 31 23:40:27.130164 ignition[880]: disks: disks passed Oct 31 23:40:27.135094 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 31 23:40:27.130224 ignition[880]: Ignition finished successfully Oct 31 23:40:27.137230 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 23:40:27.139315 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 23:40:27.141388 systemd[1]: Reached target basic.target - Basic System. Oct 31 23:40:27.143832 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 31 23:40:27.181937 systemd-fsck[891]: ROOT: clean, 15/456736 files, 38230/456704 blocks Oct 31 23:40:27.187005 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 31 23:40:27.190744 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 31 23:40:27.250264 kernel: EXT4-fs (vda9): mounted filesystem 05e98b18-3239-47ba-ae00-e111ba073d2e r/w with ordered data mode. Quota mode: none. Oct 31 23:40:27.250762 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 31 23:40:27.252099 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 31 23:40:27.254633 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 23:40:27.256335 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 31 23:40:27.257391 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 31 23:40:27.257426 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 31 23:40:27.257451 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 23:40:27.278036 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 31 23:40:27.281453 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 31 23:40:27.284365 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (899) Oct 31 23:40:27.284386 kernel: BTRFS info (device vda6): first mount of filesystem c6954884-bcd5-4df1-a2fb-0b060c87e5c9 Oct 31 23:40:27.286274 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 31 23:40:27.289957 kernel: BTRFS info (device vda6): turning on async discard Oct 31 23:40:27.289990 kernel: BTRFS info (device vda6): enabling free space tree Oct 31 23:40:27.289006 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 23:40:27.291372 systemd-networkd[735]: eth0: Gained IPv6LL Oct 31 23:40:27.321290 initrd-setup-root[923]: cut: /sysroot/etc/passwd: No such file or directory Oct 31 23:40:27.325393 initrd-setup-root[930]: cut: /sysroot/etc/group: No such file or directory Oct 31 23:40:27.329567 initrd-setup-root[937]: cut: /sysroot/etc/shadow: No such file or directory Oct 31 23:40:27.332934 initrd-setup-root[944]: cut: /sysroot/etc/gshadow: No such file or directory Oct 31 23:40:27.397736 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 31 23:40:27.400142 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 31 23:40:27.401839 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 31 23:40:27.419353 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 31 23:40:27.422303 kernel: BTRFS info (device vda6): last unmount of filesystem c6954884-bcd5-4df1-a2fb-0b060c87e5c9 Oct 31 23:40:27.435377 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 31 23:40:27.449177 ignition[1013]: INFO : Ignition 2.22.0 Oct 31 23:40:27.449177 ignition[1013]: INFO : Stage: mount Oct 31 23:40:27.451774 ignition[1013]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 23:40:27.451774 ignition[1013]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 23:40:27.451774 ignition[1013]: INFO : mount: mount passed Oct 31 23:40:27.451774 ignition[1013]: INFO : Ignition finished successfully Oct 31 23:40:27.452881 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 31 23:40:27.455885 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 31 23:40:27.475017 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 31 23:40:27.499270 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1026) Oct 31 23:40:27.501422 kernel: BTRFS info (device vda6): first mount of filesystem c6954884-bcd5-4df1-a2fb-0b060c87e5c9 Oct 31 23:40:27.501457 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 31 23:40:27.504270 kernel: BTRFS info (device vda6): turning on async discard Oct 31 23:40:27.504300 kernel: BTRFS info (device vda6): enabling free space tree Oct 31 23:40:27.505421 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 31 23:40:27.537303 ignition[1043]: INFO : Ignition 2.22.0 Oct 31 23:40:27.537303 ignition[1043]: INFO : Stage: files Oct 31 23:40:27.539014 ignition[1043]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 23:40:27.539014 ignition[1043]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 23:40:27.539014 ignition[1043]: DEBUG : files: compiled without relabeling support, skipping Oct 31 23:40:27.539014 ignition[1043]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 31 23:40:27.539014 ignition[1043]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 31 23:40:27.545942 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 31 23:40:27.541975 unknown[1043]: wrote ssh authorized keys file for user: core Oct 31 23:40:27.560949 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 31 23:40:27.560949 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 31 23:40:27.560949 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Oct 31 23:40:27.968299 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 31 23:40:28.230456 ignition[1043]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 31 23:40:28.230456 ignition[1043]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 31 23:40:28.234591 ignition[1043]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 31 23:40:28.234591 ignition[1043]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 31 23:40:28.234591 ignition[1043]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 31 23:40:28.234591 ignition[1043]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 31 23:40:28.247108 ignition[1043]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 31 23:40:28.250219 ignition[1043]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 31 23:40:28.251755 ignition[1043]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 31 23:40:28.251755 ignition[1043]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 31 23:40:28.251755 ignition[1043]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 31 23:40:28.251755 ignition[1043]: INFO : files: files passed Oct 31 23:40:28.251755 ignition[1043]: INFO : Ignition finished successfully Oct 31 23:40:28.253768 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 31 23:40:28.257683 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 31 23:40:28.261853 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 31 23:40:28.275442 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 31 23:40:28.275557 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 31 23:40:28.278980 initrd-setup-root-after-ignition[1074]: grep: /sysroot/oem/oem-release: No such file or directory Oct 31 23:40:28.281757 initrd-setup-root-after-ignition[1076]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 31 23:40:28.283593 initrd-setup-root-after-ignition[1080]: grep: Oct 31 23:40:28.284650 initrd-setup-root-after-ignition[1076]: grep: Oct 31 23:40:28.285392 initrd-setup-root-after-ignition[1080]: /sysroot/etc/flatcar/enabled-sysext.conf Oct 31 23:40:28.284993 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 23:40:28.291610 initrd-setup-root-after-ignition[1076]: /sysroot/usr/share/flatcar/enabled-sysext.conf Oct 31 23:40:28.291610 initrd-setup-root-after-ignition[1080]: : No such file or directory Oct 31 23:40:28.286717 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 31 23:40:28.294878 initrd-setup-root-after-ignition[1076]: : No such file or directory Oct 31 23:40:28.288452 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 31 23:40:28.333169 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 31 23:40:28.333296 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 31 23:40:28.335602 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 31 23:40:28.337494 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 31 23:40:28.339467 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 31 23:40:28.340212 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 31 23:40:28.370157 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 23:40:28.372560 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 31 23:40:28.391894 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 31 23:40:28.392039 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 31 23:40:28.394353 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 23:40:28.396509 systemd[1]: Stopped target timers.target - Timer Units. Oct 31 23:40:28.398215 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 31 23:40:28.398353 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 31 23:40:28.401019 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 31 23:40:28.403213 systemd[1]: Stopped target basic.target - Basic System. Oct 31 23:40:28.404978 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 31 23:40:28.406846 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 31 23:40:28.408899 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 31 23:40:28.410998 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 31 23:40:28.413031 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 31 23:40:28.414989 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 31 23:40:28.417015 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 31 23:40:28.419111 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 31 23:40:28.420926 systemd[1]: Stopped target swap.target - Swaps. Oct 31 23:40:28.422494 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 31 23:40:28.422613 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 31 23:40:28.425066 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 31 23:40:28.427167 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 23:40:28.429263 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 31 23:40:28.430356 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 23:40:28.432346 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 31 23:40:28.432455 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 31 23:40:28.435437 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 31 23:40:28.435601 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 31 23:40:28.437606 systemd[1]: Stopped target paths.target - Path Units. Oct 31 23:40:28.439212 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 31 23:40:28.444323 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 23:40:28.445700 systemd[1]: Stopped target slices.target - Slice Units. Oct 31 23:40:28.447978 systemd[1]: Stopped target sockets.target - Socket Units. Oct 31 23:40:28.449562 systemd[1]: iscsid.socket: Deactivated successfully. Oct 31 23:40:28.449687 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 31 23:40:28.451297 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 31 23:40:28.451422 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 31 23:40:28.453066 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 31 23:40:28.453231 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 31 23:40:28.455044 systemd[1]: ignition-files.service: Deactivated successfully. Oct 31 23:40:28.455220 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 31 23:40:28.457562 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 31 23:40:28.460039 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 31 23:40:28.461266 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 31 23:40:28.461448 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 23:40:28.463539 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 31 23:40:28.463690 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 23:40:28.465491 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 31 23:40:28.465639 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 31 23:40:28.472975 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 31 23:40:28.474297 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 31 23:40:28.481963 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 31 23:40:28.487849 ignition[1100]: INFO : Ignition 2.22.0 Oct 31 23:40:28.487849 ignition[1100]: INFO : Stage: umount Oct 31 23:40:28.490775 ignition[1100]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 31 23:40:28.490775 ignition[1100]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 31 23:40:28.490775 ignition[1100]: INFO : umount: umount passed Oct 31 23:40:28.490775 ignition[1100]: INFO : Ignition finished successfully Oct 31 23:40:28.488954 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 31 23:40:28.489091 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 31 23:40:28.490478 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 31 23:40:28.492289 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 31 23:40:28.494408 systemd[1]: Stopped target network.target - Network. Oct 31 23:40:28.495429 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 31 23:40:28.495501 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 31 23:40:28.497205 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 31 23:40:28.497268 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 31 23:40:28.498976 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 31 23:40:28.499029 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 31 23:40:28.500791 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 31 23:40:28.500837 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 31 23:40:28.503279 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 31 23:40:28.503337 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 31 23:40:28.504713 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 31 23:40:28.506513 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 31 23:40:28.514742 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 31 23:40:28.514843 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 31 23:40:28.519114 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 31 23:40:28.519215 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 31 23:40:28.523938 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 31 23:40:28.525239 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 31 23:40:28.525298 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 31 23:40:28.528093 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 31 23:40:28.528977 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 31 23:40:28.529038 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 31 23:40:28.531206 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 31 23:40:28.531251 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 31 23:40:28.533180 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 31 23:40:28.533225 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 31 23:40:28.535122 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 23:40:28.547532 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 31 23:40:28.547665 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 23:40:28.550878 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 31 23:40:28.550941 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 31 23:40:28.552277 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 31 23:40:28.552313 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 23:40:28.554271 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 31 23:40:28.554322 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 31 23:40:28.557250 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 31 23:40:28.557344 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 31 23:40:28.560151 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 31 23:40:28.560206 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 31 23:40:28.563982 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 31 23:40:28.565327 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 31 23:40:28.565389 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 31 23:40:28.567440 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 31 23:40:28.567486 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 23:40:28.569750 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 31 23:40:28.569794 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 23:40:28.571932 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 31 23:40:28.571977 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 23:40:28.574211 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 31 23:40:28.574268 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 23:40:28.577250 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 31 23:40:28.577363 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 31 23:40:28.578684 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 31 23:40:28.578764 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 31 23:40:28.581419 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 31 23:40:28.583553 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 31 23:40:28.602025 systemd[1]: Switching root. Oct 31 23:40:28.637505 systemd-journald[343]: Journal stopped Oct 31 23:40:29.420933 systemd-journald[343]: Received SIGTERM from PID 1 (systemd). Oct 31 23:40:29.420982 kernel: SELinux: policy capability network_peer_controls=1 Oct 31 23:40:29.420996 kernel: SELinux: policy capability open_perms=1 Oct 31 23:40:29.421006 kernel: SELinux: policy capability extended_socket_class=1 Oct 31 23:40:29.421021 kernel: SELinux: policy capability always_check_network=0 Oct 31 23:40:29.421032 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 31 23:40:29.421042 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 31 23:40:29.421061 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 31 23:40:29.421083 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 31 23:40:29.421093 kernel: SELinux: policy capability userspace_initial_context=0 Oct 31 23:40:29.421103 kernel: audit: type=1403 audit(1761954028.831:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 31 23:40:29.421114 systemd[1]: Successfully loaded SELinux policy in 67.766ms. Oct 31 23:40:29.421133 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.294ms. Oct 31 23:40:29.421145 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) Oct 31 23:40:29.421160 systemd[1]: Detected virtualization kvm. Oct 31 23:40:29.421171 systemd[1]: Detected architecture arm64. Oct 31 23:40:29.421181 systemd[1]: Detected first boot. Oct 31 23:40:29.421192 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 31 23:40:29.421204 zram_generator::config[1146]: No configuration found. Oct 31 23:40:29.421216 kernel: NET: Registered PF_VSOCK protocol family Oct 31 23:40:29.421227 systemd[1]: Populated /etc with preset unit settings. Oct 31 23:40:29.421237 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 31 23:40:29.421248 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 31 23:40:29.421270 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 31 23:40:29.421284 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 31 23:40:29.421299 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 31 23:40:29.421312 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 31 23:40:29.421323 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 31 23:40:29.421335 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 31 23:40:29.421346 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 31 23:40:29.421357 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 31 23:40:29.421367 systemd[1]: Created slice user.slice - User and Session Slice. Oct 31 23:40:29.421379 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 31 23:40:29.421390 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 31 23:40:29.421402 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 31 23:40:29.421416 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 31 23:40:29.421430 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 31 23:40:29.421440 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 31 23:40:29.421457 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 31 23:40:29.421470 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 31 23:40:29.421483 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 31 23:40:29.421494 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 31 23:40:29.421505 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 31 23:40:29.421517 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 31 23:40:29.421528 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 31 23:40:29.421539 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 31 23:40:29.421551 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 31 23:40:29.421561 systemd[1]: Reached target slices.target - Slice Units. Oct 31 23:40:29.421572 systemd[1]: Reached target swap.target - Swaps. Oct 31 23:40:29.421582 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 31 23:40:29.421593 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 31 23:40:29.421604 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 31 23:40:29.421614 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 31 23:40:29.421626 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 31 23:40:29.421641 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 31 23:40:29.421651 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 31 23:40:29.421662 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 31 23:40:29.421673 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 31 23:40:29.421685 systemd[1]: Mounting media.mount - External Media Directory... Oct 31 23:40:29.421695 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 31 23:40:29.421707 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 31 23:40:29.421718 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 31 23:40:29.421729 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 31 23:40:29.421740 systemd[1]: Reached target machines.target - Containers. Oct 31 23:40:29.421751 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 31 23:40:29.421762 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 23:40:29.421773 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 31 23:40:29.421785 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 31 23:40:29.421796 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 23:40:29.421808 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 23:40:29.421819 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 23:40:29.421829 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 31 23:40:29.421840 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 23:40:29.421851 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 31 23:40:29.421863 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 31 23:40:29.421874 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 31 23:40:29.421886 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 31 23:40:29.421896 kernel: fuse: init (API version 7.41) Oct 31 23:40:29.421906 systemd[1]: Stopped systemd-fsck-usr.service. Oct 31 23:40:29.421917 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 31 23:40:29.421929 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 31 23:40:29.421940 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 31 23:40:29.421951 kernel: ACPI: bus type drm_connector registered Oct 31 23:40:29.421962 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 31 23:40:29.421972 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 31 23:40:29.422026 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 31 23:40:29.422042 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 31 23:40:29.422063 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 31 23:40:29.422094 systemd-journald[1214]: Collecting audit messages is disabled. Oct 31 23:40:29.422116 systemd-journald[1214]: Journal started Oct 31 23:40:29.422138 systemd-journald[1214]: Runtime Journal (/run/log/journal/a9f5e2cc0f50470384d13daab048c976) is 6M, max 48.5M, 42.4M free. Oct 31 23:40:29.189439 systemd[1]: Queued start job for default target multi-user.target. Oct 31 23:40:29.212340 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 31 23:40:29.212761 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 31 23:40:29.424615 systemd[1]: Started systemd-journald.service - Journal Service. Oct 31 23:40:29.425598 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 31 23:40:29.426681 systemd[1]: Mounted media.mount - External Media Directory. Oct 31 23:40:29.427662 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 31 23:40:29.428768 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 31 23:40:29.429869 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 31 23:40:29.431016 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 31 23:40:29.432469 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 31 23:40:29.433790 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 31 23:40:29.433949 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 31 23:40:29.435314 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 23:40:29.435469 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 23:40:29.437636 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 23:40:29.437883 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 23:40:29.439173 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 23:40:29.439469 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 23:40:29.440779 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 31 23:40:29.440926 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 31 23:40:29.442349 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 23:40:29.442504 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 23:40:29.443979 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 31 23:40:29.445576 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 31 23:40:29.447833 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 31 23:40:29.449532 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 31 23:40:29.461828 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 31 23:40:29.463499 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 31 23:40:29.465833 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 31 23:40:29.467837 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 31 23:40:29.469060 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 31 23:40:29.469097 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 31 23:40:29.471025 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 31 23:40:29.472665 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 23:40:29.479615 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 31 23:40:29.481744 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 31 23:40:29.482951 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 23:40:29.483865 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 31 23:40:29.485113 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 23:40:29.488372 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 31 23:40:29.490624 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 31 23:40:29.491592 systemd-journald[1214]: Time spent on flushing to /var/log/journal/a9f5e2cc0f50470384d13daab048c976 is 16.287ms for 861 entries. Oct 31 23:40:29.491592 systemd-journald[1214]: System Journal (/var/log/journal/a9f5e2cc0f50470384d13daab048c976) is 8M, max 163.5M, 155.5M free. Oct 31 23:40:29.513392 systemd-journald[1214]: Received client request to flush runtime journal. Oct 31 23:40:29.494528 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 31 23:40:29.500336 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 31 23:40:29.503113 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 31 23:40:29.506247 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 31 23:40:29.507902 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 31 23:40:29.512018 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 31 23:40:29.515271 kernel: loop1: detected capacity change from 0 to 119344 Oct 31 23:40:29.516282 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 31 23:40:29.518821 systemd-tmpfiles[1263]: ACLs are not supported, ignoring. Oct 31 23:40:29.518837 systemd-tmpfiles[1263]: ACLs are not supported, ignoring. Oct 31 23:40:29.520036 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 31 23:40:29.524669 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 31 23:40:29.526829 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 31 23:40:29.531063 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 31 23:40:29.546410 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 31 23:40:29.557279 kernel: loop2: detected capacity change from 0 to 100624 Oct 31 23:40:29.566886 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 31 23:40:29.569725 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 31 23:40:29.571650 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 31 23:40:29.589803 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 31 23:40:29.591330 kernel: loop3: detected capacity change from 0 to 211168 Oct 31 23:40:29.597039 systemd-tmpfiles[1284]: ACLs are not supported, ignoring. Oct 31 23:40:29.597073 systemd-tmpfiles[1284]: ACLs are not supported, ignoring. Oct 31 23:40:29.600207 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 31 23:40:29.623292 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 31 23:40:29.627289 kernel: loop4: detected capacity change from 0 to 119344 Oct 31 23:40:29.633275 kernel: loop5: detected capacity change from 0 to 100624 Oct 31 23:40:29.639273 kernel: loop6: detected capacity change from 0 to 211168 Oct 31 23:40:29.644326 (sd-merge)[1293]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw'. Oct 31 23:40:29.646983 (sd-merge)[1293]: Merged extensions into '/usr'. Oct 31 23:40:29.651075 systemd[1]: Reload requested from client PID 1262 ('systemd-sysext') (unit systemd-sysext.service)... Oct 31 23:40:29.651195 systemd[1]: Reloading... Oct 31 23:40:29.688666 systemd-resolved[1283]: Positive Trust Anchors: Oct 31 23:40:29.688684 systemd-resolved[1283]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 31 23:40:29.688688 systemd-resolved[1283]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 31 23:40:29.688720 systemd-resolved[1283]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 31 23:40:29.695840 systemd-resolved[1283]: Defaulting to hostname 'linux'. Oct 31 23:40:29.704274 zram_generator::config[1326]: No configuration found. Oct 31 23:40:29.832964 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 31 23:40:29.833359 systemd[1]: Reloading finished in 181 ms. Oct 31 23:40:29.863786 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 31 23:40:29.866341 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 31 23:40:29.869381 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 31 23:40:29.885424 systemd[1]: Starting ensure-sysext.service... Oct 31 23:40:29.887176 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 31 23:40:29.896214 systemd[1]: Reload requested from client PID 1356 ('systemctl') (unit ensure-sysext.service)... Oct 31 23:40:29.896229 systemd[1]: Reloading... Oct 31 23:40:29.903639 systemd-tmpfiles[1357]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 31 23:40:29.903953 systemd-tmpfiles[1357]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 31 23:40:29.904290 systemd-tmpfiles[1357]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 31 23:40:29.904567 systemd-tmpfiles[1357]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 31 23:40:29.905247 systemd-tmpfiles[1357]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 31 23:40:29.905567 systemd-tmpfiles[1357]: ACLs are not supported, ignoring. Oct 31 23:40:29.905671 systemd-tmpfiles[1357]: ACLs are not supported, ignoring. Oct 31 23:40:29.909338 systemd-tmpfiles[1357]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 23:40:29.909348 systemd-tmpfiles[1357]: Skipping /boot Oct 31 23:40:29.915288 systemd-tmpfiles[1357]: Detected autofs mount point /boot during canonicalization of boot. Oct 31 23:40:29.915381 systemd-tmpfiles[1357]: Skipping /boot Oct 31 23:40:29.940295 zram_generator::config[1392]: No configuration found. Oct 31 23:40:30.062393 systemd[1]: Reloading finished in 165 ms. Oct 31 23:40:30.076292 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 31 23:40:30.104334 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 31 23:40:30.112820 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 31 23:40:30.115147 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 31 23:40:30.116464 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 23:40:30.127033 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 31 23:40:30.129131 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 23:40:30.131453 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 23:40:30.134488 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 23:40:30.135733 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 23:40:30.135855 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 31 23:40:30.136854 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 31 23:40:30.141501 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 31 23:40:30.146178 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 31 23:40:30.149385 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 23:40:30.149549 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 23:40:30.152296 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 23:40:30.154289 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 23:40:30.156705 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 23:40:30.156862 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 23:40:30.162741 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 23:40:30.164845 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 23:40:30.169921 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 23:40:30.172190 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 31 23:40:30.174482 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 23:40:30.174600 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 31 23:40:30.178430 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 31 23:40:30.180387 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 23:40:30.180537 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 23:40:30.181314 systemd-udevd[1436]: Using default interface naming scheme 'v257'. Oct 31 23:40:30.186035 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 23:40:30.186240 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 23:40:30.194514 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 31 23:40:30.198981 augenrules[1460]: No rules Oct 31 23:40:30.199533 systemd[1]: audit-rules.service: Deactivated successfully. Oct 31 23:40:30.200355 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 31 23:40:30.204838 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 31 23:40:30.209528 systemd[1]: Finished ensure-sysext.service. Oct 31 23:40:30.210891 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 31 23:40:30.213130 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 31 23:40:30.213314 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 31 23:40:30.222549 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 31 23:40:30.223598 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 31 23:40:30.226376 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 31 23:40:30.229304 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 31 23:40:30.231503 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 31 23:40:30.231549 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 31 23:40:30.233385 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 31 23:40:30.244033 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 31 23:40:30.247446 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 31 23:40:30.252903 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 31 23:40:30.253101 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 31 23:40:30.263598 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 31 23:40:30.263804 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 31 23:40:30.266567 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 31 23:40:30.268851 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 31 23:40:30.279209 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 31 23:40:30.279320 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 31 23:40:30.279369 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 31 23:40:30.310828 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 31 23:40:30.318507 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 31 23:40:30.347378 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 31 23:40:30.352585 systemd-networkd[1493]: lo: Link UP Oct 31 23:40:30.352601 systemd-networkd[1493]: lo: Gained carrier Oct 31 23:40:30.353834 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 31 23:40:30.354068 systemd-networkd[1493]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 31 23:40:30.354077 systemd-networkd[1493]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 31 23:40:30.354907 systemd-networkd[1493]: eth0: Link UP Oct 31 23:40:30.355025 systemd-networkd[1493]: eth0: Gained carrier Oct 31 23:40:30.355036 systemd-networkd[1493]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 31 23:40:30.355415 systemd[1]: Reached target network.target - Network. Oct 31 23:40:30.358409 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 31 23:40:30.360688 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 31 23:40:30.365820 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 31 23:40:30.367285 systemd[1]: Reached target time-set.target - System Time Set. Oct 31 23:40:30.369319 systemd-networkd[1493]: eth0: DHCPv4 address 10.0.0.49/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 31 23:40:30.369770 systemd-timesyncd[1494]: Network configuration changed, trying to establish connection. Oct 31 23:40:30.370933 systemd-timesyncd[1494]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 31 23:40:30.370980 systemd-timesyncd[1494]: Initial clock synchronization to Fri 2025-10-31 23:40:30.647334 UTC. Oct 31 23:40:30.385349 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 31 23:40:30.422674 ldconfig[1425]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 31 23:40:30.427508 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 31 23:40:30.431439 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 31 23:40:30.448483 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 31 23:40:30.459481 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 31 23:40:30.492215 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 31 23:40:30.494573 systemd[1]: Reached target sysinit.target - System Initialization. Oct 31 23:40:30.495728 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 31 23:40:30.496984 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 31 23:40:30.498424 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 31 23:40:30.499710 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 31 23:40:30.500971 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 31 23:40:30.502263 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 31 23:40:30.502293 systemd[1]: Reached target paths.target - Path Units. Oct 31 23:40:30.503169 systemd[1]: Reached target timers.target - Timer Units. Oct 31 23:40:30.505051 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 31 23:40:30.507327 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 31 23:40:30.509918 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 31 23:40:30.511380 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 31 23:40:30.512665 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 31 23:40:30.515604 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 31 23:40:30.516917 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 31 23:40:30.518619 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 31 23:40:30.519764 systemd[1]: Reached target sockets.target - Socket Units. Oct 31 23:40:30.520770 systemd[1]: Reached target basic.target - Basic System. Oct 31 23:40:30.521760 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 31 23:40:30.521788 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 31 23:40:30.522643 systemd[1]: Starting containerd.service - containerd container runtime... Oct 31 23:40:30.524581 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 31 23:40:30.526432 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 31 23:40:30.528472 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 31 23:40:30.530375 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 31 23:40:30.531457 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 31 23:40:30.532482 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 31 23:40:30.534879 jq[1545]: false Oct 31 23:40:30.535320 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 31 23:40:30.538374 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 31 23:40:30.542800 extend-filesystems[1546]: Found /dev/vda6 Oct 31 23:40:30.543933 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 31 23:40:30.545224 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 31 23:40:30.545613 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 31 23:40:30.546364 systemd[1]: Starting update-engine.service - Update Engine... Oct 31 23:40:30.547032 extend-filesystems[1546]: Found /dev/vda9 Oct 31 23:40:30.548897 extend-filesystems[1546]: Checking size of /dev/vda9 Oct 31 23:40:30.550073 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 31 23:40:30.557021 jq[1565]: true Oct 31 23:40:30.557732 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 31 23:40:30.559306 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 31 23:40:30.559495 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 31 23:40:30.559730 systemd[1]: motdgen.service: Deactivated successfully. Oct 31 23:40:30.559876 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 31 23:40:30.561498 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 31 23:40:30.561667 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 31 23:40:30.563994 extend-filesystems[1546]: Resized partition /dev/vda9 Oct 31 23:40:30.568552 extend-filesystems[1577]: resize2fs 1.47.3 (8-Jul-2025) Oct 31 23:40:30.573893 update_engine[1560]: I20251031 23:40:30.572685 1560 main.cc:92] Flatcar Update Engine starting Oct 31 23:40:30.580285 jq[1575]: true Oct 31 23:40:30.583632 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 1784827 blocks Oct 31 23:40:30.606389 dbus-daemon[1543]: [system] SELinux support is enabled Oct 31 23:40:30.606795 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 31 23:40:30.607537 (ntainerd)[1586]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 31 23:40:30.610072 update_engine[1560]: I20251031 23:40:30.609994 1560 update_check_scheduler.cc:74] Next update check in 7m38s Oct 31 23:40:30.611226 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 31 23:40:30.611423 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 31 23:40:30.612799 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 31 23:40:30.613360 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 31 23:40:30.616891 systemd[1]: Started update-engine.service - Update Engine. Oct 31 23:40:30.620684 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 31 23:40:30.627802 kernel: EXT4-fs (vda9): resized filesystem to 1784827 Oct 31 23:40:30.645018 extend-filesystems[1577]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 31 23:40:30.645018 extend-filesystems[1577]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 31 23:40:30.645018 extend-filesystems[1577]: The filesystem on /dev/vda9 is now 1784827 (4k) blocks long. Oct 31 23:40:30.652235 extend-filesystems[1546]: Resized filesystem in /dev/vda9 Oct 31 23:40:30.651108 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 31 23:40:30.665355 bash[1605]: Updated "/home/core/.ssh/authorized_keys" Oct 31 23:40:30.651398 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 31 23:40:30.657752 systemd-logind[1558]: Watching system buttons on /dev/input/event0 (Power Button) Oct 31 23:40:30.657983 systemd-logind[1558]: New seat seat0. Oct 31 23:40:30.658647 systemd[1]: Started systemd-logind.service - User Login Management. Oct 31 23:40:30.665423 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 31 23:40:30.667519 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 31 23:40:30.680874 locksmithd[1598]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 31 23:40:30.766781 containerd[1586]: time="2025-10-31T23:40:30Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 31 23:40:30.768676 containerd[1586]: time="2025-10-31T23:40:30.768631760Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 31 23:40:30.776015 sshd_keygen[1566]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 31 23:40:30.779308 containerd[1586]: time="2025-10-31T23:40:30.779249440Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.52µs" Oct 31 23:40:30.779390 containerd[1586]: time="2025-10-31T23:40:30.779374920Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 31 23:40:30.779460 containerd[1586]: time="2025-10-31T23:40:30.779445600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 31 23:40:30.779658 containerd[1586]: time="2025-10-31T23:40:30.779639080Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 31 23:40:30.779724 containerd[1586]: time="2025-10-31T23:40:30.779711200Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 31 23:40:30.779822 containerd[1586]: time="2025-10-31T23:40:30.779805200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 31 23:40:30.779932 containerd[1586]: time="2025-10-31T23:40:30.779913280Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 31 23:40:30.779982 containerd[1586]: time="2025-10-31T23:40:30.779969600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780235 containerd[1586]: time="2025-10-31T23:40:30.780213160Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780318 containerd[1586]: time="2025-10-31T23:40:30.780304560Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780369 containerd[1586]: time="2025-10-31T23:40:30.780356760Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780428 containerd[1586]: time="2025-10-31T23:40:30.780415960Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780553 containerd[1586]: time="2025-10-31T23:40:30.780535840Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780854 containerd[1586]: time="2025-10-31T23:40:30.780833240Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780945 containerd[1586]: time="2025-10-31T23:40:30.780929960Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 31 23:40:30.780993 containerd[1586]: time="2025-10-31T23:40:30.780981040Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 31 23:40:30.781080 containerd[1586]: time="2025-10-31T23:40:30.781065320Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 31 23:40:30.781347 containerd[1586]: time="2025-10-31T23:40:30.781330760Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 31 23:40:30.781459 containerd[1586]: time="2025-10-31T23:40:30.781444000Z" level=info msg="metadata content store policy set" policy=shared Oct 31 23:40:30.785135 containerd[1586]: time="2025-10-31T23:40:30.785108200Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 31 23:40:30.785286 containerd[1586]: time="2025-10-31T23:40:30.785270200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 31 23:40:30.785342 containerd[1586]: time="2025-10-31T23:40:30.785330120Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 31 23:40:30.785392 containerd[1586]: time="2025-10-31T23:40:30.785380080Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 31 23:40:30.785443 containerd[1586]: time="2025-10-31T23:40:30.785430400Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 31 23:40:30.785494 containerd[1586]: time="2025-10-31T23:40:30.785481840Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 31 23:40:30.785544 containerd[1586]: time="2025-10-31T23:40:30.785532120Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 31 23:40:30.785597 containerd[1586]: time="2025-10-31T23:40:30.785583920Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 31 23:40:30.785662 containerd[1586]: time="2025-10-31T23:40:30.785649640Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 31 23:40:30.785717 containerd[1586]: time="2025-10-31T23:40:30.785705400Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 31 23:40:30.785772 containerd[1586]: time="2025-10-31T23:40:30.785758440Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 31 23:40:30.785822 containerd[1586]: time="2025-10-31T23:40:30.785810320Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 31 23:40:30.785969 containerd[1586]: time="2025-10-31T23:40:30.785950080Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 31 23:40:30.786058 containerd[1586]: time="2025-10-31T23:40:30.786030880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 31 23:40:30.786124 containerd[1586]: time="2025-10-31T23:40:30.786111400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 31 23:40:30.786181 containerd[1586]: time="2025-10-31T23:40:30.786169520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 31 23:40:30.786230 containerd[1586]: time="2025-10-31T23:40:30.786218080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 31 23:40:30.786302 containerd[1586]: time="2025-10-31T23:40:30.786287960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 31 23:40:30.786353 containerd[1586]: time="2025-10-31T23:40:30.786341400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 31 23:40:30.786413 containerd[1586]: time="2025-10-31T23:40:30.786401640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 31 23:40:30.786464 containerd[1586]: time="2025-10-31T23:40:30.786453160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 31 23:40:30.786520 containerd[1586]: time="2025-10-31T23:40:30.786508360Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 31 23:40:30.786568 containerd[1586]: time="2025-10-31T23:40:30.786556720Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 31 23:40:30.786791 containerd[1586]: time="2025-10-31T23:40:30.786777400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 31 23:40:30.786846 containerd[1586]: time="2025-10-31T23:40:30.786834000Z" level=info msg="Start snapshots syncer" Oct 31 23:40:30.786918 containerd[1586]: time="2025-10-31T23:40:30.786904800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 31 23:40:30.787191 containerd[1586]: time="2025-10-31T23:40:30.787152600Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 31 23:40:30.787364 containerd[1586]: time="2025-10-31T23:40:30.787346440Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 31 23:40:30.787526 containerd[1586]: time="2025-10-31T23:40:30.787508040Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 31 23:40:30.787678 containerd[1586]: time="2025-10-31T23:40:30.787659640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 31 23:40:30.787745 containerd[1586]: time="2025-10-31T23:40:30.787730920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 31 23:40:30.787793 containerd[1586]: time="2025-10-31T23:40:30.787781240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 31 23:40:30.787844 containerd[1586]: time="2025-10-31T23:40:30.787832000Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 31 23:40:30.787910 containerd[1586]: time="2025-10-31T23:40:30.787896840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 31 23:40:30.787969 containerd[1586]: time="2025-10-31T23:40:30.787956560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 31 23:40:30.788020 containerd[1586]: time="2025-10-31T23:40:30.788008040Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 31 23:40:30.788098 containerd[1586]: time="2025-10-31T23:40:30.788084840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 31 23:40:30.788150 containerd[1586]: time="2025-10-31T23:40:30.788137720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 31 23:40:30.788199 containerd[1586]: time="2025-10-31T23:40:30.788187560Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 31 23:40:30.788313 containerd[1586]: time="2025-10-31T23:40:30.788296440Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 31 23:40:30.788370 containerd[1586]: time="2025-10-31T23:40:30.788356920Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 31 23:40:30.788416 containerd[1586]: time="2025-10-31T23:40:30.788405000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 31 23:40:30.788463 containerd[1586]: time="2025-10-31T23:40:30.788450240Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 31 23:40:30.788507 containerd[1586]: time="2025-10-31T23:40:30.788495120Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 31 23:40:30.788564 containerd[1586]: time="2025-10-31T23:40:30.788551360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 31 23:40:30.788627 containerd[1586]: time="2025-10-31T23:40:30.788614640Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 31 23:40:30.788746 containerd[1586]: time="2025-10-31T23:40:30.788735160Z" level=info msg="runtime interface created" Oct 31 23:40:30.788789 containerd[1586]: time="2025-10-31T23:40:30.788777400Z" level=info msg="created NRI interface" Oct 31 23:40:30.788838 containerd[1586]: time="2025-10-31T23:40:30.788826160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 31 23:40:30.788889 containerd[1586]: time="2025-10-31T23:40:30.788877240Z" level=info msg="Connect containerd service" Oct 31 23:40:30.788968 containerd[1586]: time="2025-10-31T23:40:30.788953960Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 31 23:40:30.789719 containerd[1586]: time="2025-10-31T23:40:30.789692800Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 31 23:40:30.794186 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 31 23:40:30.797057 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 31 23:40:30.817535 systemd[1]: issuegen.service: Deactivated successfully. Oct 31 23:40:30.817747 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 31 23:40:30.820705 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 31 23:40:30.847025 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 31 23:40:30.850365 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 31 23:40:30.853135 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 31 23:40:30.855118 systemd[1]: Reached target getty.target - Login Prompts. Oct 31 23:40:30.855429 containerd[1586]: time="2025-10-31T23:40:30.855361160Z" level=info msg="Start subscribing containerd event" Oct 31 23:40:30.855635 containerd[1586]: time="2025-10-31T23:40:30.855597440Z" level=info msg="Start recovering state" Oct 31 23:40:30.855769 containerd[1586]: time="2025-10-31T23:40:30.855736200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 31 23:40:30.855801 containerd[1586]: time="2025-10-31T23:40:30.855791200Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 31 23:40:30.855982 containerd[1586]: time="2025-10-31T23:40:30.855951360Z" level=info msg="Start event monitor" Oct 31 23:40:30.856076 containerd[1586]: time="2025-10-31T23:40:30.856063840Z" level=info msg="Start cni network conf syncer for default" Oct 31 23:40:30.856228 containerd[1586]: time="2025-10-31T23:40:30.856215960Z" level=info msg="Start streaming server" Oct 31 23:40:30.856362 containerd[1586]: time="2025-10-31T23:40:30.856344120Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 31 23:40:30.856589 containerd[1586]: time="2025-10-31T23:40:30.856543320Z" level=info msg="runtime interface starting up..." Oct 31 23:40:30.857467 containerd[1586]: time="2025-10-31T23:40:30.857323520Z" level=info msg="starting plugins..." Oct 31 23:40:30.857467 containerd[1586]: time="2025-10-31T23:40:30.857363840Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 31 23:40:30.857541 containerd[1586]: time="2025-10-31T23:40:30.857483960Z" level=info msg="containerd successfully booted in 0.091043s" Oct 31 23:40:30.857584 systemd[1]: Started containerd.service - containerd container runtime. Oct 31 23:40:31.835413 systemd-networkd[1493]: eth0: Gained IPv6LL Oct 31 23:40:31.838413 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 31 23:40:31.840058 systemd[1]: Reached target network-online.target - Network is Online. Oct 31 23:40:31.842583 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 31 23:40:31.844988 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 31 23:40:31.861781 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 31 23:40:31.881656 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 31 23:40:31.883347 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 31 23:40:31.883598 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 31 23:40:31.886283 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 31 23:40:32.414135 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 31 23:40:32.415924 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 31 23:40:32.417723 (kubelet)[1674]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 31 23:40:32.420754 systemd[1]: Startup finished in 1.158s (kernel) + 3.736s (initrd) + 3.657s (userspace) = 8.552s. Oct 31 23:40:32.763228 kubelet[1674]: E1031 23:40:32.763180 1674 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Oct 31 23:40:32.765956 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 31 23:40:32.766084 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 31 23:40:32.766395 systemd[1]: kubelet.service: Consumed 739ms CPU time, 255.5M memory peak. Oct 31 23:40:36.355655 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 31 23:40:36.356701 systemd[1]: Started sshd@0-10.0.0.49:22-10.0.0.1:47950.service - OpenSSH per-connection server daemon (10.0.0.1:47950). Oct 31 23:40:36.423546 sshd[1687]: Accepted publickey for core from 10.0.0.1 port 47950 ssh2: RSA SHA256:Ig5g5KZpHiq2tjGdIDYbclLg0xOliIGuSDyrlFKI8nU Oct 31 23:40:36.425391 sshd-session[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 23:40:36.431330 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 31 23:40:36.432433 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 31 23:40:36.437815 systemd-logind[1558]: New session 1 of user core. Oct 31 23:40:36.451329 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 31 23:40:36.453737 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 31 23:40:36.470045 (systemd)[1692]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 31 23:40:36.472037 systemd-logind[1558]: New session c1 of user core. Oct 31 23:40:36.576940 systemd[1692]: Queued start job for default target default.target. Oct 31 23:40:36.591188 systemd[1692]: Created slice app.slice - User Application Slice. Oct 31 23:40:36.591219 systemd[1692]: Reached target paths.target - Paths. Oct 31 23:40:36.591255 systemd[1692]: Reached target timers.target - Timers. Oct 31 23:40:36.592451 systemd[1692]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 31 23:40:36.601556 systemd[1692]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 31 23:40:36.601616 systemd[1692]: Reached target sockets.target - Sockets. Oct 31 23:40:36.601658 systemd[1692]: Reached target basic.target - Basic System. Oct 31 23:40:36.601686 systemd[1692]: Reached target default.target - Main User Target. Oct 31 23:40:36.601709 systemd[1692]: Startup finished in 124ms. Oct 31 23:40:36.601866 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 31 23:40:36.603013 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 31 23:40:36.664663 systemd[1]: Started sshd@1-10.0.0.49:22-10.0.0.1:47952.service - OpenSSH per-connection server daemon (10.0.0.1:47952). Oct 31 23:40:36.709396 sshd[1703]: Accepted publickey for core from 10.0.0.1 port 47952 ssh2: RSA SHA256:Ig5g5KZpHiq2tjGdIDYbclLg0xOliIGuSDyrlFKI8nU Oct 31 23:40:36.710709 sshd-session[1703]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 23:40:36.715050 systemd-logind[1558]: New session 2 of user core. Oct 31 23:40:36.725456 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 31 23:40:36.778191 sshd[1706]: Connection closed by 10.0.0.1 port 47952 Oct 31 23:40:36.778061 sshd-session[1703]: pam_unix(sshd:session): session closed for user core Oct 31 23:40:36.789255 systemd[1]: sshd@1-10.0.0.49:22-10.0.0.1:47952.service: Deactivated successfully. Oct 31 23:40:36.791579 systemd[1]: session-2.scope: Deactivated successfully. Oct 31 23:40:36.792286 systemd-logind[1558]: Session 2 logged out. Waiting for processes to exit. Oct 31 23:40:36.795315 systemd[1]: Started sshd@2-10.0.0.49:22-10.0.0.1:47966.service - OpenSSH per-connection server daemon (10.0.0.1:47966). Oct 31 23:40:36.795935 systemd-logind[1558]: Removed session 2. Oct 31 23:40:36.853814 sshd[1712]: Accepted publickey for core from 10.0.0.1 port 47966 ssh2: RSA SHA256:Ig5g5KZpHiq2tjGdIDYbclLg0xOliIGuSDyrlFKI8nU Oct 31 23:40:36.855137 sshd-session[1712]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 23:40:36.858757 systemd-logind[1558]: New session 3 of user core. Oct 31 23:40:36.870436 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 31 23:40:36.918629 sshd[1715]: Connection closed by 10.0.0.1 port 47966 Oct 31 23:40:36.918989 sshd-session[1712]: pam_unix(sshd:session): session closed for user core Oct 31 23:40:36.929082 systemd[1]: sshd@2-10.0.0.49:22-10.0.0.1:47966.service: Deactivated successfully. Oct 31 23:40:36.931455 systemd[1]: session-3.scope: Deactivated successfully. Oct 31 23:40:36.932095 systemd-logind[1558]: Session 3 logged out. Waiting for processes to exit. Oct 31 23:40:36.934151 systemd[1]: Started sshd@3-10.0.0.49:22-10.0.0.1:47972.service - OpenSSH per-connection server daemon (10.0.0.1:47972). Oct 31 23:40:36.934626 systemd-logind[1558]: Removed session 3. Oct 31 23:40:36.994297 sshd[1721]: Accepted publickey for core from 10.0.0.1 port 47972 ssh2: RSA SHA256:Ig5g5KZpHiq2tjGdIDYbclLg0xOliIGuSDyrlFKI8nU Oct 31 23:40:36.995340 sshd-session[1721]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 23:40:36.999082 systemd-logind[1558]: New session 4 of user core. Oct 31 23:40:37.007138 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 31 23:40:37.060300 sshd[1725]: Connection closed by 10.0.0.1 port 47972 Oct 31 23:40:37.060878 sshd-session[1721]: pam_unix(sshd:session): session closed for user core Oct 31 23:40:37.074135 systemd[1]: sshd@3-10.0.0.49:22-10.0.0.1:47972.service: Deactivated successfully. Oct 31 23:40:37.077490 systemd[1]: session-4.scope: Deactivated successfully. Oct 31 23:40:37.078125 systemd-logind[1558]: Session 4 logged out. Waiting for processes to exit. Oct 31 23:40:37.080105 systemd[1]: Started sshd@4-10.0.0.49:22-10.0.0.1:47982.service - OpenSSH per-connection server daemon (10.0.0.1:47982). Oct 31 23:40:37.080691 systemd-logind[1558]: Removed session 4. Oct 31 23:40:37.130455 sshd[1731]: Accepted publickey for core from 10.0.0.1 port 47982 ssh2: RSA SHA256:Ig5g5KZpHiq2tjGdIDYbclLg0xOliIGuSDyrlFKI8nU Oct 31 23:40:37.131565 sshd-session[1731]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 31 23:40:37.135120 systemd-logind[1558]: New session 5 of user core. Oct 31 23:40:37.146412 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 31 23:40:37.204730 sudo[1735]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 31 23:40:37.205014 sudo[1735]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 31 23:40:37.216159 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 31 23:40:37.248522 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 31 23:40:37.248753 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 31 23:40:37.647922 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 31 23:40:37.648071 systemd[1]: kubelet.service: Consumed 739ms CPU time, 255.5M memory peak. Oct 31 23:40:37.649961 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 31 23:40:37.671564 systemd[1]: Reload requested from client PID 1779 ('systemctl') (unit session-5.scope)... Oct 31 23:40:37.671580 systemd[1]: Reloading... Oct 31 23:40:37.739329 zram_generator::config[1821]: No configuration found. Oct 31 23:40:37.987214 systemd[1]: Reloading finished in 315 ms. Oct 31 23:40:38.051810 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 31 23:40:38.051896 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 31 23:40:38.052179 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 31 23:40:38.052231 systemd[1]: kubelet.service: Consumed 95ms CPU time, 94.9M memory peak. Oct 31 23:40:38.053814 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 31 23:40:38.174653 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 31 23:40:38.178601 (kubelet)[1866]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 31 23:40:38.212511 kubelet[1866]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 31 23:40:38.212511 kubelet[1866]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 31 23:40:38.212511 kubelet[1866]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 31 23:40:38.212836 kubelet[1866]: I1031 23:40:38.212558 1866 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Oct 31 23:40:38.965386 kubelet[1866]: I1031 23:40:38.965331 1866 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Oct 31 23:40:38.965386 kubelet[1866]: I1031 23:40:38.965364 1866 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 31 23:40:38.965608 kubelet[1866]: I1031 23:40:38.965571 1866 server.go:956] "Client rotation is on, will bootstrap in background" Oct 31 23:40:38.986999 kubelet[1866]: I1031 23:40:38.986949 1866 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 31 23:40:38.995296 kubelet[1866]: I1031 23:40:38.993677 1866 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 31 23:40:38.996594 kubelet[1866]: I1031 23:40:38.996554 1866 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 31 23:40:38.998107 kubelet[1866]: I1031 23:40:38.998069 1866 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 31 23:40:38.998362 kubelet[1866]: I1031 23:40:38.998175 1866 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.49","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":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 31 23:40:38.999242 kubelet[1866]: I1031 23:40:38.998898 1866 topology_manager.go:138] "Creating topology manager with none policy" Oct 31 23:40:38.999347 kubelet[1866]: I1031 23:40:38.999328 1866 container_manager_linux.go:303] "Creating device plugin manager" Oct 31 23:40:38.999553 kubelet[1866]: I1031 23:40:38.999531 1866 state_mem.go:36] "Initialized new in-memory state store" Oct 31 23:40:39.002624 kubelet[1866]: I1031 23:40:39.002599 1866 kubelet.go:480] "Attempting to sync node with API server" Oct 31 23:40:39.003001 kubelet[1866]: I1031 23:40:39.002980 1866 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 31 23:40:39.003039 kubelet[1866]: I1031 23:40:39.003012 1866 kubelet.go:386] "Adding apiserver pod source" Oct 31 23:40:39.003039 kubelet[1866]: I1031 23:40:39.003027 1866 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 31 23:40:39.003130 kubelet[1866]: E1031 23:40:39.003099 1866 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:39.003391 kubelet[1866]: E1031 23:40:39.003360 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:39.004236 kubelet[1866]: I1031 23:40:39.004220 1866 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Oct 31 23:40:39.005196 kubelet[1866]: I1031 23:40:39.005170 1866 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 31 23:40:39.005436 kubelet[1866]: W1031 23:40:39.005420 1866 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 31 23:40:39.007733 kubelet[1866]: I1031 23:40:39.007715 1866 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 31 23:40:39.007829 kubelet[1866]: I1031 23:40:39.007819 1866 server.go:1289] "Started kubelet" Oct 31 23:40:39.007943 kubelet[1866]: I1031 23:40:39.007916 1866 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Oct 31 23:40:39.008921 kubelet[1866]: I1031 23:40:39.008869 1866 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 31 23:40:39.009031 kubelet[1866]: I1031 23:40:39.009017 1866 server.go:317] "Adding debug handlers to kubelet server" Oct 31 23:40:39.011482 kubelet[1866]: I1031 23:40:39.011427 1866 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 31 23:40:39.011964 kubelet[1866]: E1031 23:40:39.011816 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.011964 kubelet[1866]: I1031 23:40:39.011959 1866 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 31 23:40:39.012171 kubelet[1866]: I1031 23:40:39.012143 1866 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 31 23:40:39.012236 kubelet[1866]: I1031 23:40:39.012220 1866 reconciler.go:26] "Reconciler: start to sync state" Oct 31 23:40:39.013158 kubelet[1866]: I1031 23:40:39.013087 1866 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Oct 31 23:40:39.014933 kubelet[1866]: E1031 23:40:39.013434 1866 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.49.1873b7dcfd9fbb92 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.49,UID:10.0.0.49,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.49,},FirstTimestamp:2025-10-31 23:40:39.007787922 +0000 UTC m=+0.826138051,LastTimestamp:2025-10-31 23:40:39.007787922 +0000 UTC m=+0.826138051,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.49,}" Oct 31 23:40:39.014933 kubelet[1866]: E1031 23:40:39.014599 1866 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Oct 31 23:40:39.014933 kubelet[1866]: E1031 23:40:39.014699 1866 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.49\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Oct 31 23:40:39.014933 kubelet[1866]: I1031 23:40:39.014700 1866 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 31 23:40:39.016554 kubelet[1866]: I1031 23:40:39.016518 1866 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 31 23:40:39.017957 kubelet[1866]: E1031 23:40:39.017920 1866 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 31 23:40:39.018378 kubelet[1866]: I1031 23:40:39.018357 1866 factory.go:223] Registration of the containerd container factory successfully Oct 31 23:40:39.018378 kubelet[1866]: I1031 23:40:39.018377 1866 factory.go:223] Registration of the systemd container factory successfully Oct 31 23:40:39.027283 kubelet[1866]: E1031 23:40:39.027039 1866 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.49\" not found" node="10.0.0.49" Oct 31 23:40:39.031234 kubelet[1866]: I1031 23:40:39.031218 1866 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 31 23:40:39.031371 kubelet[1866]: I1031 23:40:39.031359 1866 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 31 23:40:39.031430 kubelet[1866]: I1031 23:40:39.031422 1866 state_mem.go:36] "Initialized new in-memory state store" Oct 31 23:40:39.112609 kubelet[1866]: E1031 23:40:39.112565 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.137142 kubelet[1866]: I1031 23:40:39.137111 1866 policy_none.go:49] "None policy: Start" Oct 31 23:40:39.137142 kubelet[1866]: I1031 23:40:39.137143 1866 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 31 23:40:39.137232 kubelet[1866]: I1031 23:40:39.137155 1866 state_mem.go:35] "Initializing new in-memory state store" Oct 31 23:40:39.146891 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 31 23:40:39.158689 kubelet[1866]: I1031 23:40:39.158660 1866 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Oct 31 23:40:39.158878 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 31 23:40:39.159766 kubelet[1866]: I1031 23:40:39.159739 1866 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Oct 31 23:40:39.159766 kubelet[1866]: I1031 23:40:39.159762 1866 status_manager.go:230] "Starting to sync pod status with apiserver" Oct 31 23:40:39.159839 kubelet[1866]: I1031 23:40:39.159788 1866 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 31 23:40:39.159839 kubelet[1866]: I1031 23:40:39.159796 1866 kubelet.go:2436] "Starting kubelet main sync loop" Oct 31 23:40:39.159839 kubelet[1866]: E1031 23:40:39.159831 1866 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Oct 31 23:40:39.163093 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 31 23:40:39.179281 kubelet[1866]: E1031 23:40:39.179251 1866 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 31 23:40:39.179495 kubelet[1866]: I1031 23:40:39.179475 1866 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 31 23:40:39.179534 kubelet[1866]: I1031 23:40:39.179496 1866 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 31 23:40:39.179764 kubelet[1866]: I1031 23:40:39.179729 1866 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 31 23:40:39.180387 kubelet[1866]: E1031 23:40:39.180353 1866 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 31 23:40:39.180486 kubelet[1866]: E1031 23:40:39.180475 1866 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.49\" not found" Oct 31 23:40:39.281064 kubelet[1866]: I1031 23:40:39.280969 1866 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.49" Oct 31 23:40:39.286122 kubelet[1866]: I1031 23:40:39.285988 1866 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.49" Oct 31 23:40:39.286122 kubelet[1866]: E1031 23:40:39.286021 1866 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.49\": node \"10.0.0.49\" not found" Oct 31 23:40:39.293920 kubelet[1866]: E1031 23:40:39.293876 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.397681 kubelet[1866]: E1031 23:40:39.397606 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.498444 kubelet[1866]: E1031 23:40:39.498393 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.600881 kubelet[1866]: E1031 23:40:39.600638 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.696965 sudo[1735]: pam_unix(sudo:session): session closed for user root Oct 31 23:40:39.698576 sshd[1734]: Connection closed by 10.0.0.1 port 47982 Oct 31 23:40:39.698911 sshd-session[1731]: pam_unix(sshd:session): session closed for user core Oct 31 23:40:39.701343 kubelet[1866]: E1031 23:40:39.701315 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.702038 systemd[1]: sshd@4-10.0.0.49:22-10.0.0.1:47982.service: Deactivated successfully. Oct 31 23:40:39.703965 systemd[1]: session-5.scope: Deactivated successfully. Oct 31 23:40:39.704343 systemd[1]: session-5.scope: Consumed 404ms CPU time, 76.7M memory peak. Oct 31 23:40:39.705940 systemd-logind[1558]: Session 5 logged out. Waiting for processes to exit. Oct 31 23:40:39.707190 systemd-logind[1558]: Removed session 5. Oct 31 23:40:39.802178 kubelet[1866]: E1031 23:40:39.802123 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.903273 kubelet[1866]: E1031 23:40:39.903169 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:39.967473 kubelet[1866]: I1031 23:40:39.967414 1866 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 31 23:40:39.967677 kubelet[1866]: I1031 23:40:39.967620 1866 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 31 23:40:39.967936 kubelet[1866]: I1031 23:40:39.967766 1866 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 31 23:40:40.004114 kubelet[1866]: E1031 23:40:40.004084 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:40.004178 kubelet[1866]: E1031 23:40:40.004135 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:40.104350 kubelet[1866]: E1031 23:40:40.104295 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:40.204836 kubelet[1866]: E1031 23:40:40.204732 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:40.304922 kubelet[1866]: E1031 23:40:40.304840 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:40.406013 kubelet[1866]: E1031 23:40:40.405954 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:40.506826 kubelet[1866]: E1031 23:40:40.506730 1866 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.49\" not found" Oct 31 23:40:40.607961 kubelet[1866]: I1031 23:40:40.607922 1866 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 31 23:40:40.608276 containerd[1586]: time="2025-10-31T23:40:40.608223059Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 31 23:40:40.608694 kubelet[1866]: I1031 23:40:40.608528 1866 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 31 23:40:41.004334 kubelet[1866]: E1031 23:40:41.004208 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:41.004334 kubelet[1866]: I1031 23:40:41.004280 1866 apiserver.go:52] "Watching apiserver" Oct 31 23:40:41.019122 systemd[1]: Created slice kubepods-burstable-pod9ca511d9_cca2_4971_84aa_a1bae1e10f45.slice - libcontainer container kubepods-burstable-pod9ca511d9_cca2_4971_84aa_a1bae1e10f45.slice. Oct 31 23:40:41.025179 kubelet[1866]: I1031 23:40:41.025145 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/9ca511d9-cca2-4971-84aa-a1bae1e10f45-cni-plugin\") pod \"kube-flannel-ds-zp87l\" (UID: \"9ca511d9-cca2-4971-84aa-a1bae1e10f45\") " pod="kube-flannel/kube-flannel-ds-zp87l" Oct 31 23:40:41.025359 kubelet[1866]: I1031 23:40:41.025191 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/9ca511d9-cca2-4971-84aa-a1bae1e10f45-flannel-cfg\") pod \"kube-flannel-ds-zp87l\" (UID: \"9ca511d9-cca2-4971-84aa-a1bae1e10f45\") " pod="kube-flannel/kube-flannel-ds-zp87l" Oct 31 23:40:41.025359 kubelet[1866]: I1031 23:40:41.025277 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x8gnf\" (UniqueName: \"kubernetes.io/projected/9ca511d9-cca2-4971-84aa-a1bae1e10f45-kube-api-access-x8gnf\") pod \"kube-flannel-ds-zp87l\" (UID: \"9ca511d9-cca2-4971-84aa-a1bae1e10f45\") " pod="kube-flannel/kube-flannel-ds-zp87l" Oct 31 23:40:41.025359 kubelet[1866]: I1031 23:40:41.025309 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/816d1af0-b923-4844-8f8c-67f8f4d8ecc0-lib-modules\") pod \"kube-proxy-fnh5t\" (UID: \"816d1af0-b923-4844-8f8c-67f8f4d8ecc0\") " pod="kube-system/kube-proxy-fnh5t" Oct 31 23:40:41.025359 kubelet[1866]: I1031 23:40:41.025339 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-88lzz\" (UniqueName: \"kubernetes.io/projected/816d1af0-b923-4844-8f8c-67f8f4d8ecc0-kube-api-access-88lzz\") pod \"kube-proxy-fnh5t\" (UID: \"816d1af0-b923-4844-8f8c-67f8f4d8ecc0\") " pod="kube-system/kube-proxy-fnh5t" Oct 31 23:40:41.025359 kubelet[1866]: I1031 23:40:41.025360 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/9ca511d9-cca2-4971-84aa-a1bae1e10f45-run\") pod \"kube-flannel-ds-zp87l\" (UID: \"9ca511d9-cca2-4971-84aa-a1bae1e10f45\") " pod="kube-flannel/kube-flannel-ds-zp87l" Oct 31 23:40:41.025477 kubelet[1866]: I1031 23:40:41.025374 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/9ca511d9-cca2-4971-84aa-a1bae1e10f45-cni\") pod \"kube-flannel-ds-zp87l\" (UID: \"9ca511d9-cca2-4971-84aa-a1bae1e10f45\") " pod="kube-flannel/kube-flannel-ds-zp87l" Oct 31 23:40:41.025477 kubelet[1866]: I1031 23:40:41.025389 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/9ca511d9-cca2-4971-84aa-a1bae1e10f45-xtables-lock\") pod \"kube-flannel-ds-zp87l\" (UID: \"9ca511d9-cca2-4971-84aa-a1bae1e10f45\") " pod="kube-flannel/kube-flannel-ds-zp87l" Oct 31 23:40:41.025477 kubelet[1866]: I1031 23:40:41.025409 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/816d1af0-b923-4844-8f8c-67f8f4d8ecc0-kube-proxy\") pod \"kube-proxy-fnh5t\" (UID: \"816d1af0-b923-4844-8f8c-67f8f4d8ecc0\") " pod="kube-system/kube-proxy-fnh5t" Oct 31 23:40:41.025477 kubelet[1866]: I1031 23:40:41.025424 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/816d1af0-b923-4844-8f8c-67f8f4d8ecc0-xtables-lock\") pod \"kube-proxy-fnh5t\" (UID: \"816d1af0-b923-4844-8f8c-67f8f4d8ecc0\") " pod="kube-system/kube-proxy-fnh5t" Oct 31 23:40:41.039325 systemd[1]: Created slice kubepods-besteffort-pod816d1af0_b923_4844_8f8c_67f8f4d8ecc0.slice - libcontainer container kubepods-besteffort-pod816d1af0_b923_4844_8f8c_67f8f4d8ecc0.slice. Oct 31 23:40:41.113392 kubelet[1866]: I1031 23:40:41.113345 1866 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 31 23:40:41.336079 kubelet[1866]: E1031 23:40:41.335951 1866 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" Oct 31 23:40:41.336884 containerd[1586]: time="2025-10-31T23:40:41.336604217Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-zp87l,Uid:9ca511d9-cca2-4971-84aa-a1bae1e10f45,Namespace:kube-flannel,Attempt:0,}" Oct 31 23:40:41.350017 kubelet[1866]: E1031 23:40:41.349991 1866 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" Oct 31 23:40:41.350589 containerd[1586]: time="2025-10-31T23:40:41.350537343Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-fnh5t,Uid:816d1af0-b923-4844-8f8c-67f8f4d8ecc0,Namespace:kube-system,Attempt:0,}" Oct 31 23:40:41.849023 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount991124127.mount: Deactivated successfully. Oct 31 23:40:41.856813 containerd[1586]: time="2025-10-31T23:40:41.856773673Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 31 23:40:41.857392 containerd[1586]: time="2025-10-31T23:40:41.857358986Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Oct 31 23:40:41.858731 containerd[1586]: time="2025-10-31T23:40:41.858697566Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 31 23:40:41.859391 containerd[1586]: time="2025-10-31T23:40:41.859365705Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 31 23:40:41.859856 containerd[1586]: time="2025-10-31T23:40:41.859832188Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 31 23:40:41.863325 containerd[1586]: time="2025-10-31T23:40:41.863290343Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 31 23:40:41.864105 containerd[1586]: time="2025-10-31T23:40:41.864079371Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 509.537229ms" Oct 31 23:40:41.864673 containerd[1586]: time="2025-10-31T23:40:41.864652132Z" 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 522.805888ms" Oct 31 23:40:41.880903 containerd[1586]: time="2025-10-31T23:40:41.880866328Z" level=info msg="connecting to shim 7ddc6d9372e5c3ff210be810a757c821291dc66a1721bb0c951bd500e7fbac5f" address="unix:///run/containerd/s/97be08e4fdda7956fb67e7a8e5eb5f07b633c419849d552e660019d2aa0bad3c" namespace=k8s.io protocol=ttrpc version=3 Oct 31 23:40:41.882174 containerd[1586]: time="2025-10-31T23:40:41.882140810Z" level=info msg="connecting to shim 9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5" address="unix:///run/containerd/s/117d9c11c6c5a9c5fc65199321dfd2a9e7363fcf23ad4371075a2ecd020096cc" namespace=k8s.io protocol=ttrpc version=3 Oct 31 23:40:41.910506 systemd[1]: Started cri-containerd-7ddc6d9372e5c3ff210be810a757c821291dc66a1721bb0c951bd500e7fbac5f.scope - libcontainer container 7ddc6d9372e5c3ff210be810a757c821291dc66a1721bb0c951bd500e7fbac5f. Oct 31 23:40:41.912133 systemd[1]: Started cri-containerd-9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5.scope - libcontainer container 9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5. Oct 31 23:40:41.948197 containerd[1586]: time="2025-10-31T23:40:41.948133994Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-fnh5t,Uid:816d1af0-b923-4844-8f8c-67f8f4d8ecc0,Namespace:kube-system,Attempt:0,} returns sandbox id \"7ddc6d9372e5c3ff210be810a757c821291dc66a1721bb0c951bd500e7fbac5f\"" Oct 31 23:40:41.949133 kubelet[1866]: E1031 23:40:41.949109 1866 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" Oct 31 23:40:41.950185 containerd[1586]: time="2025-10-31T23:40:41.950158311Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Oct 31 23:40:41.978633 containerd[1586]: time="2025-10-31T23:40:41.978596032Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-zp87l,Uid:9ca511d9-cca2-4971-84aa-a1bae1e10f45,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5\"" Oct 31 23:40:41.979583 kubelet[1866]: E1031 23:40:41.979419 1866 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" Oct 31 23:40:42.004380 kubelet[1866]: E1031 23:40:42.004347 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:42.994675 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount475241995.mount: Deactivated successfully. Oct 31 23:40:43.004812 kubelet[1866]: E1031 23:40:43.004767 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:43.236938 containerd[1586]: time="2025-10-31T23:40:43.236882824Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:43.237591 containerd[1586]: time="2025-10-31T23:40:43.237566550Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240108" Oct 31 23:40:43.238213 containerd[1586]: time="2025-10-31T23:40:43.238191308Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:43.240386 containerd[1586]: time="2025-10-31T23:40:43.240330253Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:43.241040 containerd[1586]: time="2025-10-31T23:40:43.240845414Z" 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.290651644s" Oct 31 23:40:43.241040 containerd[1586]: time="2025-10-31T23:40:43.240876670Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Oct 31 23:40:43.242033 containerd[1586]: time="2025-10-31T23:40:43.242002693Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 31 23:40:43.245543 containerd[1586]: time="2025-10-31T23:40:43.245449881Z" level=info msg="CreateContainer within sandbox \"7ddc6d9372e5c3ff210be810a757c821291dc66a1721bb0c951bd500e7fbac5f\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 31 23:40:43.255231 containerd[1586]: time="2025-10-31T23:40:43.255204226Z" level=info msg="Container 373a07283b3f42be489a9dd4a84517980bd5889577e0da5f0a8cb882e7bb93cf: CDI devices from CRI Config.CDIDevices: []" Oct 31 23:40:43.262038 containerd[1586]: time="2025-10-31T23:40:43.261985378Z" level=info msg="CreateContainer within sandbox \"7ddc6d9372e5c3ff210be810a757c821291dc66a1721bb0c951bd500e7fbac5f\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"373a07283b3f42be489a9dd4a84517980bd5889577e0da5f0a8cb882e7bb93cf\"" Oct 31 23:40:43.263315 containerd[1586]: time="2025-10-31T23:40:43.262828567Z" level=info msg="StartContainer for \"373a07283b3f42be489a9dd4a84517980bd5889577e0da5f0a8cb882e7bb93cf\"" Oct 31 23:40:43.264361 containerd[1586]: time="2025-10-31T23:40:43.264317982Z" level=info msg="connecting to shim 373a07283b3f42be489a9dd4a84517980bd5889577e0da5f0a8cb882e7bb93cf" address="unix:///run/containerd/s/97be08e4fdda7956fb67e7a8e5eb5f07b633c419849d552e660019d2aa0bad3c" protocol=ttrpc version=3 Oct 31 23:40:43.293432 systemd[1]: Started cri-containerd-373a07283b3f42be489a9dd4a84517980bd5889577e0da5f0a8cb882e7bb93cf.scope - libcontainer container 373a07283b3f42be489a9dd4a84517980bd5889577e0da5f0a8cb882e7bb93cf. Oct 31 23:40:43.323829 containerd[1586]: time="2025-10-31T23:40:43.323718654Z" level=info msg="StartContainer for \"373a07283b3f42be489a9dd4a84517980bd5889577e0da5f0a8cb882e7bb93cf\" returns successfully" Oct 31 23:40:44.005592 kubelet[1866]: E1031 23:40:44.005548 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:44.147863 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount593308227.mount: Deactivated successfully. Oct 31 23:40:44.172514 kubelet[1866]: E1031 23:40:44.172478 1866 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" Oct 31 23:40:44.181837 kubelet[1866]: I1031 23:40:44.181704 1866 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-fnh5t" podStartSLOduration=3.889705524 podStartE2EDuration="5.181692735s" podCreationTimestamp="2025-10-31 23:40:39 +0000 UTC" firstStartedPulling="2025-10-31 23:40:41.949890297 +0000 UTC m=+3.768240385" lastFinishedPulling="2025-10-31 23:40:43.241877508 +0000 UTC m=+5.060227596" observedRunningTime="2025-10-31 23:40:44.181160674 +0000 UTC m=+5.999510762" watchObservedRunningTime="2025-10-31 23:40:44.181692735 +0000 UTC m=+6.000042823" Oct 31 23:40:44.184799 containerd[1586]: time="2025-10-31T23:40:44.184760184Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:44.185189 containerd[1586]: time="2025-10-31T23:40:44.185155899Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Oct 31 23:40:44.186165 containerd[1586]: time="2025-10-31T23:40:44.186140660Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:44.188737 containerd[1586]: time="2025-10-31T23:40:44.188684177Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:44.190521 containerd[1586]: time="2025-10-31T23:40:44.189834219Z" 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 947.796815ms" Oct 31 23:40:44.190521 containerd[1586]: time="2025-10-31T23:40:44.189869432Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 31 23:40:44.193249 containerd[1586]: time="2025-10-31T23:40:44.193217701Z" level=info msg="CreateContainer within sandbox \"9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 31 23:40:44.198756 containerd[1586]: time="2025-10-31T23:40:44.198727333Z" level=info msg="Container a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0: CDI devices from CRI Config.CDIDevices: []" Oct 31 23:40:44.204068 containerd[1586]: time="2025-10-31T23:40:44.204038282Z" level=info msg="CreateContainer within sandbox \"9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0\"" Oct 31 23:40:44.204540 containerd[1586]: time="2025-10-31T23:40:44.204494202Z" level=info msg="StartContainer for \"a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0\"" Oct 31 23:40:44.205369 containerd[1586]: time="2025-10-31T23:40:44.205346039Z" level=info msg="connecting to shim a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0" address="unix:///run/containerd/s/117d9c11c6c5a9c5fc65199321dfd2a9e7363fcf23ad4371075a2ecd020096cc" protocol=ttrpc version=3 Oct 31 23:40:44.236428 systemd[1]: Started cri-containerd-a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0.scope - libcontainer container a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0. Oct 31 23:40:44.260411 systemd[1]: cri-containerd-a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0.scope: Deactivated successfully. Oct 31 23:40:44.262296 containerd[1586]: time="2025-10-31T23:40:44.262234409Z" level=info msg="received exit event container_id:\"a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0\" id:\"a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0\" pid:2205 exited_at:{seconds:1761954044 nanos:261142037}" Oct 31 23:40:44.262529 containerd[1586]: time="2025-10-31T23:40:44.262376429Z" level=info msg="TaskExit event in podsandbox handler container_id:\"a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0\" id:\"a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0\" pid:2205 exited_at:{seconds:1761954044 nanos:261142037}" Oct 31 23:40:44.268638 containerd[1586]: time="2025-10-31T23:40:44.268612378Z" level=info msg="StartContainer for \"a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0\" returns successfully" Oct 31 23:40:44.279060 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a1fb25a6f4ed4da3103eb2a669e942d17ef011168d4e2e0f7107b8276a2d3fb0-rootfs.mount: Deactivated successfully. Oct 31 23:40:45.006481 kubelet[1866]: E1031 23:40:45.006441 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:45.175352 kubelet[1866]: E1031 23:40:45.174918 1866 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" Oct 31 23:40:45.175352 kubelet[1866]: E1031 23:40:45.174985 1866 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" Oct 31 23:40:45.175666 containerd[1586]: time="2025-10-31T23:40:45.175630584Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 31 23:40:46.008197 kubelet[1866]: E1031 23:40:46.007345 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:46.513497 containerd[1586]: time="2025-10-31T23:40:46.513438042Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:46.514697 containerd[1586]: time="2025-10-31T23:40:46.514477667Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Oct 31 23:40:46.516023 containerd[1586]: time="2025-10-31T23:40:46.515996355Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:46.519217 containerd[1586]: time="2025-10-31T23:40:46.519185075Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:46.520690 containerd[1586]: time="2025-10-31T23:40:46.520661286Z" 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.344989127s" Oct 31 23:40:46.520784 containerd[1586]: time="2025-10-31T23:40:46.520693275Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 31 23:40:46.524736 containerd[1586]: time="2025-10-31T23:40:46.524691431Z" level=info msg="CreateContainer within sandbox \"9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 31 23:40:46.532915 containerd[1586]: time="2025-10-31T23:40:46.532873567Z" level=info msg="Container f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc: CDI devices from CRI Config.CDIDevices: []" Oct 31 23:40:46.540476 containerd[1586]: time="2025-10-31T23:40:46.540448403Z" level=info msg="CreateContainer within sandbox \"9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc\"" Oct 31 23:40:46.540867 containerd[1586]: time="2025-10-31T23:40:46.540845326Z" level=info msg="StartContainer for \"f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc\"" Oct 31 23:40:46.541896 containerd[1586]: time="2025-10-31T23:40:46.541859714Z" level=info msg="connecting to shim f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc" address="unix:///run/containerd/s/117d9c11c6c5a9c5fc65199321dfd2a9e7363fcf23ad4371075a2ecd020096cc" protocol=ttrpc version=3 Oct 31 23:40:46.571446 systemd[1]: Started cri-containerd-f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc.scope - libcontainer container f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc. Oct 31 23:40:46.592936 systemd[1]: cri-containerd-f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc.scope: Deactivated successfully. Oct 31 23:40:46.594424 containerd[1586]: time="2025-10-31T23:40:46.593418089Z" level=info msg="TaskExit event in podsandbox handler container_id:\"f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc\" id:\"f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc\" pid:2284 exited_at:{seconds:1761954046 nanos:593130715}" Oct 31 23:40:46.594949 containerd[1586]: time="2025-10-31T23:40:46.594924883Z" level=info msg="received exit event container_id:\"f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc\" id:\"f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc\" pid:2284 exited_at:{seconds:1761954046 nanos:593130715}" Oct 31 23:40:46.596018 containerd[1586]: time="2025-10-31T23:40:46.595997260Z" level=info msg="StartContainer for \"f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc\" returns successfully" Oct 31 23:40:46.610361 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-f7004ca1d3f5f3c96c2157112117f7e385d10b0bc376f87c3330b90b21256cbc-rootfs.mount: Deactivated successfully. Oct 31 23:40:46.617383 kubelet[1866]: I1031 23:40:46.617326 1866 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 31 23:40:47.007609 kubelet[1866]: E1031 23:40:47.007460 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:47.179272 kubelet[1866]: E1031 23:40:47.179180 1866 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" Oct 31 23:40:47.182306 containerd[1586]: time="2025-10-31T23:40:47.182241137Z" level=info msg="CreateContainer within sandbox \"9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 31 23:40:47.189487 containerd[1586]: time="2025-10-31T23:40:47.189445772Z" level=info msg="Container 8e0a5d6d520fd3165b4744395cd6a4b7dbd40fafe4a5c1d9ea789e73cdd236c7: CDI devices from CRI Config.CDIDevices: []" Oct 31 23:40:47.195015 containerd[1586]: time="2025-10-31T23:40:47.194968654Z" level=info msg="CreateContainer within sandbox \"9285f894690866563788181be4d7fdddeb10f094a6c26365a8b58345b7c239d5\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"8e0a5d6d520fd3165b4744395cd6a4b7dbd40fafe4a5c1d9ea789e73cdd236c7\"" Oct 31 23:40:47.195444 containerd[1586]: time="2025-10-31T23:40:47.195406513Z" level=info msg="StartContainer for \"8e0a5d6d520fd3165b4744395cd6a4b7dbd40fafe4a5c1d9ea789e73cdd236c7\"" Oct 31 23:40:47.196143 containerd[1586]: time="2025-10-31T23:40:47.196102421Z" level=info msg="connecting to shim 8e0a5d6d520fd3165b4744395cd6a4b7dbd40fafe4a5c1d9ea789e73cdd236c7" address="unix:///run/containerd/s/117d9c11c6c5a9c5fc65199321dfd2a9e7363fcf23ad4371075a2ecd020096cc" protocol=ttrpc version=3 Oct 31 23:40:47.215405 systemd[1]: Started cri-containerd-8e0a5d6d520fd3165b4744395cd6a4b7dbd40fafe4a5c1d9ea789e73cdd236c7.scope - libcontainer container 8e0a5d6d520fd3165b4744395cd6a4b7dbd40fafe4a5c1d9ea789e73cdd236c7. Oct 31 23:40:47.240446 containerd[1586]: time="2025-10-31T23:40:47.240411429Z" level=info msg="StartContainer for \"8e0a5d6d520fd3165b4744395cd6a4b7dbd40fafe4a5c1d9ea789e73cdd236c7\" returns successfully" Oct 31 23:40:48.007928 kubelet[1866]: E1031 23:40:48.007885 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:48.183043 kubelet[1866]: E1031 23:40:48.183016 1866 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" Oct 31 23:40:48.193107 kubelet[1866]: I1031 23:40:48.192889 1866 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-zp87l" podStartSLOduration=4.6511910279999995 podStartE2EDuration="9.19287509s" podCreationTimestamp="2025-10-31 23:40:39 +0000 UTC" firstStartedPulling="2025-10-31 23:40:41.979791926 +0000 UTC m=+3.798141974" lastFinishedPulling="2025-10-31 23:40:46.521475948 +0000 UTC m=+8.339826036" observedRunningTime="2025-10-31 23:40:48.192485705 +0000 UTC m=+10.010835793" watchObservedRunningTime="2025-10-31 23:40:48.19287509 +0000 UTC m=+10.011225178" Oct 31 23:40:48.299538 systemd-networkd[1493]: flannel.1: Link UP Oct 31 23:40:48.299543 systemd-networkd[1493]: flannel.1: Gained carrier Oct 31 23:40:49.008520 kubelet[1866]: E1031 23:40:49.008475 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:49.183822 kubelet[1866]: E1031 23:40:49.183796 1866 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" Oct 31 23:40:50.009678 kubelet[1866]: E1031 23:40:50.009621 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:50.203455 systemd-networkd[1493]: flannel.1: Gained IPv6LL Oct 31 23:40:50.666749 systemd[1]: Created slice kubepods-besteffort-pod0b11ad1a_9f1e_4da7_9a20_5fccb9b255b4.slice - libcontainer container kubepods-besteffort-pod0b11ad1a_9f1e_4da7_9a20_5fccb9b255b4.slice. Oct 31 23:40:50.691826 kubelet[1866]: I1031 23:40:50.691785 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h68m6\" (UniqueName: \"kubernetes.io/projected/0b11ad1a-9f1e-4da7-9a20-5fccb9b255b4-kube-api-access-h68m6\") pod \"nginx-deployment-7fcdb87857-6xb58\" (UID: \"0b11ad1a-9f1e-4da7-9a20-5fccb9b255b4\") " pod="default/nginx-deployment-7fcdb87857-6xb58" Oct 31 23:40:50.970019 containerd[1586]: time="2025-10-31T23:40:50.969922635Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6xb58,Uid:0b11ad1a-9f1e-4da7-9a20-5fccb9b255b4,Namespace:default,Attempt:0,}" Oct 31 23:40:50.982538 systemd-networkd[1493]: cni0: Link UP Oct 31 23:40:50.982544 systemd-networkd[1493]: cni0: Gained carrier Oct 31 23:40:50.983670 systemd-networkd[1493]: cni0: Lost carrier Oct 31 23:40:50.987366 systemd-networkd[1493]: veth347f2bf0: Link UP Oct 31 23:40:50.990627 kernel: cni0: port 1(veth347f2bf0) entered blocking state Oct 31 23:40:50.990693 kernel: cni0: port 1(veth347f2bf0) entered disabled state Oct 31 23:40:50.990715 kernel: veth347f2bf0: entered allmulticast mode Oct 31 23:40:50.992290 kernel: veth347f2bf0: entered promiscuous mode Oct 31 23:40:50.997502 kernel: cni0: port 1(veth347f2bf0) entered blocking state Oct 31 23:40:50.997551 kernel: cni0: port 1(veth347f2bf0) entered forwarding state Oct 31 23:40:50.998692 systemd-networkd[1493]: veth347f2bf0: Gained carrier Oct 31 23:40:50.998960 systemd-networkd[1493]: cni0: Gained carrier Oct 31 23:40:51.001094 containerd[1586]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 31 23:40:51.001094 containerd[1586]: delegateAdd: netconf sent to delegate plugin: Oct 31 23:40:51.009963 kubelet[1866]: E1031 23:40:51.009902 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:51.022556 containerd[1586]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-31T23:40:51.022502137Z" level=info msg="connecting to shim 243f43c790a8ac68948da847b0f1b01b8974caf4ce3ccc8e3388770d434c1788" address="unix:///run/containerd/s/392dfff3a9748ecae5729f055ef96308a3cbb6f5c30d768924c7f44e6862ee9a" namespace=k8s.io protocol=ttrpc version=3 Oct 31 23:40:51.052615 systemd[1]: Started cri-containerd-243f43c790a8ac68948da847b0f1b01b8974caf4ce3ccc8e3388770d434c1788.scope - libcontainer container 243f43c790a8ac68948da847b0f1b01b8974caf4ce3ccc8e3388770d434c1788. Oct 31 23:40:51.062142 systemd-resolved[1283]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 31 23:40:51.084971 containerd[1586]: time="2025-10-31T23:40:51.084913005Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6xb58,Uid:0b11ad1a-9f1e-4da7-9a20-5fccb9b255b4,Namespace:default,Attempt:0,} returns sandbox id \"243f43c790a8ac68948da847b0f1b01b8974caf4ce3ccc8e3388770d434c1788\"" Oct 31 23:40:51.086298 containerd[1586]: time="2025-10-31T23:40:51.086266314Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 31 23:40:52.010821 kubelet[1866]: E1031 23:40:52.010764 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:52.379409 systemd-networkd[1493]: cni0: Gained IPv6LL Oct 31 23:40:52.706199 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4070891126.mount: Deactivated successfully. Oct 31 23:40:52.827401 systemd-networkd[1493]: veth347f2bf0: Gained IPv6LL Oct 31 23:40:53.011280 kubelet[1866]: E1031 23:40:53.011158 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:53.371523 containerd[1586]: time="2025-10-31T23:40:53.371298923Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:53.371803 containerd[1586]: time="2025-10-31T23:40:53.371694125Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Oct 31 23:40:53.372714 containerd[1586]: time="2025-10-31T23:40:53.372669869Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:53.375165 containerd[1586]: time="2025-10-31T23:40:53.375118464Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:40:53.376380 containerd[1586]: time="2025-10-31T23:40:53.376350877Z" 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.290047361s" Oct 31 23:40:53.376428 containerd[1586]: time="2025-10-31T23:40:53.376386622Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 31 23:40:53.379272 containerd[1586]: time="2025-10-31T23:40:53.379234868Z" level=info msg="CreateContainer within sandbox \"243f43c790a8ac68948da847b0f1b01b8974caf4ce3ccc8e3388770d434c1788\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 31 23:40:53.393957 containerd[1586]: time="2025-10-31T23:40:53.393925959Z" level=info msg="Container 3ed443551b84dd31e67200c036d87e657142caf4509ec27a16c5438ce56d7c22: CDI devices from CRI Config.CDIDevices: []" Oct 31 23:40:53.399328 containerd[1586]: time="2025-10-31T23:40:53.399297698Z" level=info msg="CreateContainer within sandbox \"243f43c790a8ac68948da847b0f1b01b8974caf4ce3ccc8e3388770d434c1788\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"3ed443551b84dd31e67200c036d87e657142caf4509ec27a16c5438ce56d7c22\"" Oct 31 23:40:53.400854 containerd[1586]: time="2025-10-31T23:40:53.399845459Z" level=info msg="StartContainer for \"3ed443551b84dd31e67200c036d87e657142caf4509ec27a16c5438ce56d7c22\"" Oct 31 23:40:53.400854 containerd[1586]: time="2025-10-31T23:40:53.400562890Z" level=info msg="connecting to shim 3ed443551b84dd31e67200c036d87e657142caf4509ec27a16c5438ce56d7c22" address="unix:///run/containerd/s/392dfff3a9748ecae5729f055ef96308a3cbb6f5c30d768924c7f44e6862ee9a" protocol=ttrpc version=3 Oct 31 23:40:53.422411 systemd[1]: Started cri-containerd-3ed443551b84dd31e67200c036d87e657142caf4509ec27a16c5438ce56d7c22.scope - libcontainer container 3ed443551b84dd31e67200c036d87e657142caf4509ec27a16c5438ce56d7c22. Oct 31 23:40:53.444048 containerd[1586]: time="2025-10-31T23:40:53.444015831Z" level=info msg="StartContainer for \"3ed443551b84dd31e67200c036d87e657142caf4509ec27a16c5438ce56d7c22\" returns successfully" Oct 31 23:40:54.013555 kubelet[1866]: E1031 23:40:54.013499 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:54.205328 kubelet[1866]: I1031 23:40:54.205248 1866 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-6xb58" podStartSLOduration=1.914178757 podStartE2EDuration="4.205233644s" podCreationTimestamp="2025-10-31 23:40:50 +0000 UTC" firstStartedPulling="2025-10-31 23:40:51.085924218 +0000 UTC m=+12.904274306" lastFinishedPulling="2025-10-31 23:40:53.376979105 +0000 UTC m=+15.195329193" observedRunningTime="2025-10-31 23:40:54.205060166 +0000 UTC m=+16.023410254" watchObservedRunningTime="2025-10-31 23:40:54.205233644 +0000 UTC m=+16.023583732" Oct 31 23:40:55.013862 kubelet[1866]: E1031 23:40:55.013818 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:56.014233 kubelet[1866]: E1031 23:40:56.014181 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:56.967600 systemd[1]: Created slice kubepods-besteffort-pod09e3fff7_a1d9_4402_9d84_570b28c33cf1.slice - libcontainer container kubepods-besteffort-pod09e3fff7_a1d9_4402_9d84_570b28c33cf1.slice. Oct 31 23:40:57.014807 kubelet[1866]: E1031 23:40:57.014777 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:57.033170 kubelet[1866]: I1031 23:40:57.033124 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/09e3fff7-a1d9-4402-9d84-570b28c33cf1-data\") pod \"nfs-server-provisioner-0\" (UID: \"09e3fff7-a1d9-4402-9d84-570b28c33cf1\") " pod="default/nfs-server-provisioner-0" Oct 31 23:40:57.033292 kubelet[1866]: I1031 23:40:57.033192 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-k6l88\" (UniqueName: \"kubernetes.io/projected/09e3fff7-a1d9-4402-9d84-570b28c33cf1-kube-api-access-k6l88\") pod \"nfs-server-provisioner-0\" (UID: \"09e3fff7-a1d9-4402-9d84-570b28c33cf1\") " pod="default/nfs-server-provisioner-0" Oct 31 23:40:57.270860 containerd[1586]: time="2025-10-31T23:40:57.270689340Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:09e3fff7-a1d9-4402-9d84-570b28c33cf1,Namespace:default,Attempt:0,}" Oct 31 23:40:57.285693 systemd-networkd[1493]: vethdc97187f: Link UP Oct 31 23:40:57.289615 kernel: cni0: port 2(vethdc97187f) entered blocking state Oct 31 23:40:57.289693 kernel: cni0: port 2(vethdc97187f) entered disabled state Oct 31 23:40:57.289711 kernel: vethdc97187f: entered allmulticast mode Oct 31 23:40:57.291519 kernel: vethdc97187f: entered promiscuous mode Oct 31 23:40:57.298008 kernel: cni0: port 2(vethdc97187f) entered blocking state Oct 31 23:40:57.298055 kernel: cni0: port 2(vethdc97187f) entered forwarding state Oct 31 23:40:57.297843 systemd-networkd[1493]: vethdc97187f: Gained carrier Oct 31 23:40:57.299164 containerd[1586]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 31 23:40:57.299164 containerd[1586]: delegateAdd: netconf sent to delegate plugin: Oct 31 23:40:57.319381 containerd[1586]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-31T23:40:57.319332576Z" level=info msg="connecting to shim 6077def8103532e47066d7107a58c9df5cc171f39248d0bf1008ff08e0d14350" address="unix:///run/containerd/s/c8614c1648ca63c4cacbebea520d41587bdf4285a3ad52186929cd5aa1c4b8f0" namespace=k8s.io protocol=ttrpc version=3 Oct 31 23:40:57.340398 systemd[1]: Started cri-containerd-6077def8103532e47066d7107a58c9df5cc171f39248d0bf1008ff08e0d14350.scope - libcontainer container 6077def8103532e47066d7107a58c9df5cc171f39248d0bf1008ff08e0d14350. Oct 31 23:40:57.349722 systemd-resolved[1283]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 31 23:40:57.367971 containerd[1586]: time="2025-10-31T23:40:57.367937851Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:09e3fff7-a1d9-4402-9d84-570b28c33cf1,Namespace:default,Attempt:0,} returns sandbox id \"6077def8103532e47066d7107a58c9df5cc171f39248d0bf1008ff08e0d14350\"" Oct 31 23:40:57.369292 containerd[1586]: time="2025-10-31T23:40:57.369201365Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 31 23:40:58.015571 kubelet[1866]: E1031 23:40:58.015527 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:58.984730 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2680022048.mount: Deactivated successfully. Oct 31 23:40:59.003552 kubelet[1866]: E1031 23:40:59.003524 1866 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:59.016038 kubelet[1866]: E1031 23:40:59.016008 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:40:59.035510 systemd-networkd[1493]: vethdc97187f: Gained IPv6LL Oct 31 23:41:00.016153 kubelet[1866]: E1031 23:41:00.016097 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:00.339729 containerd[1586]: time="2025-10-31T23:41:00.339609771Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:41:00.340225 containerd[1586]: time="2025-10-31T23:41:00.340030874Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Oct 31 23:41:00.341152 containerd[1586]: time="2025-10-31T23:41:00.341098241Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:41:00.343344 containerd[1586]: time="2025-10-31T23:41:00.343305347Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:41:00.344207 containerd[1586]: time="2025-10-31T23:41:00.344165685Z" 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 2.974936613s" Oct 31 23:41:00.344207 containerd[1586]: time="2025-10-31T23:41:00.344202712Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 31 23:41:00.347738 containerd[1586]: time="2025-10-31T23:41:00.347690378Z" level=info msg="CreateContainer within sandbox \"6077def8103532e47066d7107a58c9df5cc171f39248d0bf1008ff08e0d14350\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 31 23:41:00.356342 containerd[1586]: time="2025-10-31T23:41:00.356309051Z" level=info msg="Container 90d0dd7ccc6e851c1fbca91f18ada105f22629d31f77e944d6577f5b72002e39: CDI devices from CRI Config.CDIDevices: []" Oct 31 23:41:00.361980 containerd[1586]: time="2025-10-31T23:41:00.361949303Z" level=info msg="CreateContainer within sandbox \"6077def8103532e47066d7107a58c9df5cc171f39248d0bf1008ff08e0d14350\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"90d0dd7ccc6e851c1fbca91f18ada105f22629d31f77e944d6577f5b72002e39\"" Oct 31 23:41:00.362436 containerd[1586]: time="2025-10-31T23:41:00.362410595Z" level=info msg="StartContainer for \"90d0dd7ccc6e851c1fbca91f18ada105f22629d31f77e944d6577f5b72002e39\"" Oct 31 23:41:00.363308 containerd[1586]: time="2025-10-31T23:41:00.363284863Z" level=info msg="connecting to shim 90d0dd7ccc6e851c1fbca91f18ada105f22629d31f77e944d6577f5b72002e39" address="unix:///run/containerd/s/c8614c1648ca63c4cacbebea520d41587bdf4285a3ad52186929cd5aa1c4b8f0" protocol=ttrpc version=3 Oct 31 23:41:00.393495 systemd[1]: Started cri-containerd-90d0dd7ccc6e851c1fbca91f18ada105f22629d31f77e944d6577f5b72002e39.scope - libcontainer container 90d0dd7ccc6e851c1fbca91f18ada105f22629d31f77e944d6577f5b72002e39. Oct 31 23:41:00.417200 containerd[1586]: time="2025-10-31T23:41:00.417165379Z" level=info msg="StartContainer for \"90d0dd7ccc6e851c1fbca91f18ada105f22629d31f77e944d6577f5b72002e39\" returns successfully" Oct 31 23:41:01.018592 kubelet[1866]: E1031 23:41:01.018517 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:01.222224 kubelet[1866]: I1031 23:41:01.222137 1866 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.24586858 podStartE2EDuration="5.222094808s" podCreationTimestamp="2025-10-31 23:40:56 +0000 UTC" firstStartedPulling="2025-10-31 23:40:57.368969837 +0000 UTC m=+19.187319925" lastFinishedPulling="2025-10-31 23:41:00.345196065 +0000 UTC m=+22.163546153" observedRunningTime="2025-10-31 23:41:01.221745749 +0000 UTC m=+23.040095797" watchObservedRunningTime="2025-10-31 23:41:01.222094808 +0000 UTC m=+23.040444896" Oct 31 23:41:02.018859 kubelet[1866]: E1031 23:41:02.018790 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:03.019649 kubelet[1866]: E1031 23:41:03.019601 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:04.020062 kubelet[1866]: E1031 23:41:04.019939 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:05.020688 kubelet[1866]: E1031 23:41:05.020622 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:05.655413 systemd[1]: Created slice kubepods-besteffort-podb4572434_f983_4399_9f67_f50d6d546379.slice - libcontainer container kubepods-besteffort-podb4572434_f983_4399_9f67_f50d6d546379.slice. Oct 31 23:41:05.689654 kubelet[1866]: I1031 23:41:05.689611 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-e7cec620-c772-48eb-bad7-e142f51d01c8\" (UniqueName: \"kubernetes.io/nfs/b4572434-f983-4399-9f67-f50d6d546379-pvc-e7cec620-c772-48eb-bad7-e142f51d01c8\") pod \"test-pod-1\" (UID: \"b4572434-f983-4399-9f67-f50d6d546379\") " pod="default/test-pod-1" Oct 31 23:41:05.690030 kubelet[1866]: I1031 23:41:05.689900 1866 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-29j69\" (UniqueName: \"kubernetes.io/projected/b4572434-f983-4399-9f67-f50d6d546379-kube-api-access-29j69\") pod \"test-pod-1\" (UID: \"b4572434-f983-4399-9f67-f50d6d546379\") " pod="default/test-pod-1" Oct 31 23:41:05.809293 kernel: netfs: FS-Cache loaded Oct 31 23:41:05.834418 kernel: RPC: Registered named UNIX socket transport module. Oct 31 23:41:05.834525 kernel: RPC: Registered udp transport module. Oct 31 23:41:05.834548 kernel: RPC: Registered tcp transport module. Oct 31 23:41:05.835661 kernel: RPC: Registered tcp-with-tls transport module. Oct 31 23:41:05.836513 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 31 23:41:06.009519 kernel: NFS: Registering the id_resolver key type Oct 31 23:41:06.009673 kernel: Key type id_resolver registered Oct 31 23:41:06.009695 kernel: Key type id_legacy registered Oct 31 23:41:06.021781 kubelet[1866]: E1031 23:41:06.021737 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:06.029099 nfsidmap[2854]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Oct 31 23:41:06.029981 nfsidmap[2854]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 31 23:41:06.033199 nfsidmap[2857]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Oct 31 23:41:06.033409 nfsidmap[2857]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 31 23:41:06.041882 nfsrahead[2861]: setting /var/lib/kubelet/pods/b4572434-f983-4399-9f67-f50d6d546379/volumes/kubernetes.io~nfs/pvc-e7cec620-c772-48eb-bad7-e142f51d01c8 readahead to 128 Oct 31 23:41:06.258633 containerd[1586]: time="2025-10-31T23:41:06.258595747Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b4572434-f983-4399-9f67-f50d6d546379,Namespace:default,Attempt:0,}" Oct 31 23:41:06.268080 systemd-networkd[1493]: veth8a9f642c: Link UP Oct 31 23:41:06.270887 kernel: cni0: port 3(veth8a9f642c) entered blocking state Oct 31 23:41:06.270945 kernel: cni0: port 3(veth8a9f642c) entered disabled state Oct 31 23:41:06.270964 kernel: veth8a9f642c: entered allmulticast mode Oct 31 23:41:06.270981 kernel: veth8a9f642c: entered promiscuous mode Oct 31 23:41:06.278374 kernel: cni0: port 3(veth8a9f642c) entered blocking state Oct 31 23:41:06.278448 kernel: cni0: port 3(veth8a9f642c) entered forwarding state Oct 31 23:41:06.278506 systemd-networkd[1493]: veth8a9f642c: Gained carrier Oct 31 23:41:06.279977 containerd[1586]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 31 23:41:06.279977 containerd[1586]: delegateAdd: netconf sent to delegate plugin: Oct 31 23:41:06.300802 containerd[1586]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-31T23:41:06.300609678Z" level=info msg="connecting to shim 580ae6c1d9060a9b6b360d826f1c4dabe12d83f0da57bdf09414c53eae222f6a" address="unix:///run/containerd/s/875ad16feeaaa9a3e8daa8364e09273ea97ce57c9133bc30997205db6175d6cd" namespace=k8s.io protocol=ttrpc version=3 Oct 31 23:41:06.325437 systemd[1]: Started cri-containerd-580ae6c1d9060a9b6b360d826f1c4dabe12d83f0da57bdf09414c53eae222f6a.scope - libcontainer container 580ae6c1d9060a9b6b360d826f1c4dabe12d83f0da57bdf09414c53eae222f6a. Oct 31 23:41:06.335035 systemd-resolved[1283]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 31 23:41:06.353875 containerd[1586]: time="2025-10-31T23:41:06.353827260Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:b4572434-f983-4399-9f67-f50d6d546379,Namespace:default,Attempt:0,} returns sandbox id \"580ae6c1d9060a9b6b360d826f1c4dabe12d83f0da57bdf09414c53eae222f6a\"" Oct 31 23:41:06.354864 containerd[1586]: time="2025-10-31T23:41:06.354841874Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 31 23:41:06.600849 containerd[1586]: time="2025-10-31T23:41:06.600651401Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 31 23:41:06.602276 containerd[1586]: time="2025-10-31T23:41:06.601485000Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 31 23:41:06.604154 containerd[1586]: time="2025-10-31T23:41:06.604128270Z" 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 249.195428ms" Oct 31 23:41:06.604251 containerd[1586]: time="2025-10-31T23:41:06.604236286Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 31 23:41:06.607326 containerd[1586]: time="2025-10-31T23:41:06.607303219Z" level=info msg="CreateContainer within sandbox \"580ae6c1d9060a9b6b360d826f1c4dabe12d83f0da57bdf09414c53eae222f6a\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 31 23:41:06.613093 containerd[1586]: time="2025-10-31T23:41:06.613049881Z" level=info msg="Container 1ad3cc5aac79f8405b9be912677c1fd4ce6688128c232a6b5e556a9588016a80: CDI devices from CRI Config.CDIDevices: []" Oct 31 23:41:06.618133 containerd[1586]: time="2025-10-31T23:41:06.618084688Z" level=info msg="CreateContainer within sandbox \"580ae6c1d9060a9b6b360d826f1c4dabe12d83f0da57bdf09414c53eae222f6a\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"1ad3cc5aac79f8405b9be912677c1fd4ce6688128c232a6b5e556a9588016a80\"" Oct 31 23:41:06.618619 containerd[1586]: time="2025-10-31T23:41:06.618595076Z" level=info msg="StartContainer for \"1ad3cc5aac79f8405b9be912677c1fd4ce6688128c232a6b5e556a9588016a80\"" Oct 31 23:41:06.619479 containerd[1586]: time="2025-10-31T23:41:06.619444523Z" level=info msg="connecting to shim 1ad3cc5aac79f8405b9be912677c1fd4ce6688128c232a6b5e556a9588016a80" address="unix:///run/containerd/s/875ad16feeaaa9a3e8daa8364e09273ea97ce57c9133bc30997205db6175d6cd" protocol=ttrpc version=3 Oct 31 23:41:06.638408 systemd[1]: Started cri-containerd-1ad3cc5aac79f8405b9be912677c1fd4ce6688128c232a6b5e556a9588016a80.scope - libcontainer container 1ad3cc5aac79f8405b9be912677c1fd4ce6688128c232a6b5e556a9588016a80. Oct 31 23:41:06.662125 containerd[1586]: time="2025-10-31T23:41:06.661966201Z" level=info msg="StartContainer for \"1ad3cc5aac79f8405b9be912677c1fd4ce6688128c232a6b5e556a9588016a80\" returns successfully" Oct 31 23:41:07.022776 kubelet[1866]: E1031 23:41:07.022637 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:07.230728 kubelet[1866]: I1031 23:41:07.230664 1866 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=9.98038237 podStartE2EDuration="10.230649041s" podCreationTimestamp="2025-10-31 23:40:57 +0000 UTC" firstStartedPulling="2025-10-31 23:41:06.354615755 +0000 UTC m=+28.172965843" lastFinishedPulling="2025-10-31 23:41:06.604882426 +0000 UTC m=+28.423232514" observedRunningTime="2025-10-31 23:41:07.230296946 +0000 UTC m=+29.048647034" watchObservedRunningTime="2025-10-31 23:41:07.230649041 +0000 UTC m=+29.048999129" Oct 31 23:41:08.022820 kubelet[1866]: E1031 23:41:08.022775 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 31 23:41:08.059469 systemd-networkd[1493]: veth8a9f642c: Gained IPv6LL Oct 31 23:41:09.023019 kubelet[1866]: E1031 23:41:09.022983 1866 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"