Nov 5 23:30:59.848483 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 5 23:30:59.848521 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT Wed Nov 5 22:05:56 -00 2025 Nov 5 23:30:59.848531 kernel: KASLR enabled Nov 5 23:30:59.848536 kernel: efi: EFI v2.7 by EDK II Nov 5 23:30:59.848542 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbae018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40218 Nov 5 23:30:59.848548 kernel: random: crng init done Nov 5 23:30:59.848555 kernel: secureboot: Secure boot disabled Nov 5 23:30:59.848561 kernel: ACPI: Early table checksum verification disabled Nov 5 23:30:59.848566 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) Nov 5 23:30:59.848574 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 5 23:30:59.848580 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848586 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848592 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848598 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848605 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848613 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848619 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848625 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848632 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 23:30:59.848638 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 5 23:30:59.848644 kernel: NUMA: Failed to initialise from firmware Nov 5 23:30:59.848651 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 23:30:59.848657 kernel: NUMA: NODE_DATA [mem 0xdc957800-0xdc95cfff] Nov 5 23:30:59.848663 kernel: Zone ranges: Nov 5 23:30:59.848669 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 23:30:59.848676 kernel: DMA32 empty Nov 5 23:30:59.848682 kernel: Normal empty Nov 5 23:30:59.848688 kernel: Movable zone start for each node Nov 5 23:30:59.848695 kernel: Early memory node ranges Nov 5 23:30:59.848701 kernel: node 0: [mem 0x0000000040000000-0x00000000d967ffff] Nov 5 23:30:59.848707 kernel: node 0: [mem 0x00000000d9680000-0x00000000d968ffff] Nov 5 23:30:59.848713 kernel: node 0: [mem 0x00000000d9690000-0x00000000d976ffff] Nov 5 23:30:59.848719 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Nov 5 23:30:59.848725 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Nov 5 23:30:59.848732 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 5 23:30:59.848738 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 5 23:30:59.848744 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 5 23:30:59.848751 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 5 23:30:59.848758 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 23:30:59.848764 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Nov 5 23:30:59.848773 kernel: psci: probing for conduit method from ACPI. Nov 5 23:30:59.848779 kernel: psci: PSCIv1.1 detected in firmware. Nov 5 23:30:59.848786 kernel: psci: Using standard PSCI v0.2 function IDs Nov 5 23:30:59.848794 kernel: psci: Trusted OS migration not required Nov 5 23:30:59.848800 kernel: psci: SMC Calling Convention v1.1 Nov 5 23:30:59.848807 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Nov 5 23:30:59.848814 kernel: percpu: Embedded 31 pages/cpu s86120 r8192 d32664 u126976 Nov 5 23:30:59.848820 kernel: pcpu-alloc: s86120 r8192 d32664 u126976 alloc=31*4096 Nov 5 23:30:59.848827 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 5 23:30:59.848833 kernel: Detected PIPT I-cache on CPU0 Nov 5 23:30:59.848840 kernel: CPU features: detected: GIC system register CPU interface Nov 5 23:30:59.848846 kernel: CPU features: detected: Hardware dirty bit management Nov 5 23:30:59.848853 kernel: CPU features: detected: Spectre-v4 Nov 5 23:30:59.848860 kernel: CPU features: detected: Spectre-BHB Nov 5 23:30:59.848867 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 5 23:30:59.848874 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 5 23:30:59.848880 kernel: CPU features: detected: ARM erratum 1418040 Nov 5 23:30:59.848887 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 5 23:30:59.848893 kernel: alternatives: applying boot alternatives Nov 5 23:30:59.848901 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=6e2597b977e57750cfe5ec10206cda05956d53bb22ea4d0b8af9eaf567cda532 Nov 5 23:30:59.848908 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 5 23:30:59.848914 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 5 23:30:59.848921 kernel: Fallback order for Node 0: 0 Nov 5 23:30:59.848928 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 5 23:30:59.848935 kernel: Policy zone: DMA Nov 5 23:30:59.848942 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 5 23:30:59.848948 kernel: software IO TLB: area num 4. Nov 5 23:30:59.848955 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Nov 5 23:30:59.848962 kernel: Memory: 2387408K/2572288K available (10368K kernel code, 2180K rwdata, 8104K rodata, 38400K init, 897K bss, 184880K reserved, 0K cma-reserved) Nov 5 23:30:59.848968 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 5 23:30:59.848975 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 5 23:30:59.848982 kernel: rcu: RCU event tracing is enabled. Nov 5 23:30:59.848989 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 5 23:30:59.848996 kernel: Trampoline variant of Tasks RCU enabled. Nov 5 23:30:59.849003 kernel: Tracing variant of Tasks RCU enabled. Nov 5 23:30:59.849009 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 5 23:30:59.849017 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 5 23:30:59.849024 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 5 23:30:59.849030 kernel: GICv3: 256 SPIs implemented Nov 5 23:30:59.849037 kernel: GICv3: 0 Extended SPIs implemented Nov 5 23:30:59.849043 kernel: Root IRQ handler: gic_handle_irq Nov 5 23:30:59.849050 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Nov 5 23:30:59.849056 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 5 23:30:59.849063 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 5 23:30:59.849070 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Nov 5 23:30:59.849077 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Nov 5 23:30:59.849083 kernel: GICv3: using LPI property table @0x00000000400f0000 Nov 5 23:30:59.849091 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Nov 5 23:30:59.849106 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 5 23:30:59.849113 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 23:30:59.849119 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 5 23:30:59.849126 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 5 23:30:59.849133 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 5 23:30:59.849140 kernel: arm-pv: using stolen time PV Nov 5 23:30:59.849146 kernel: Console: colour dummy device 80x25 Nov 5 23:30:59.849153 kernel: ACPI: Core revision 20230628 Nov 5 23:30:59.849160 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 5 23:30:59.849167 kernel: pid_max: default: 32768 minimum: 301 Nov 5 23:30:59.849176 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 5 23:30:59.849182 kernel: landlock: Up and running. Nov 5 23:30:59.849189 kernel: SELinux: Initializing. Nov 5 23:30:59.849196 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 23:30:59.849202 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 23:30:59.849230 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 5 23:30:59.849239 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 5 23:30:59.849246 kernel: rcu: Hierarchical SRCU implementation. Nov 5 23:30:59.849253 kernel: rcu: Max phase no-delay instances is 400. Nov 5 23:30:59.849261 kernel: Platform MSI: ITS@0x8080000 domain created Nov 5 23:30:59.849268 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 5 23:30:59.849275 kernel: Remapping and enabling EFI services. Nov 5 23:30:59.849282 kernel: smp: Bringing up secondary CPUs ... Nov 5 23:30:59.849289 kernel: Detected PIPT I-cache on CPU1 Nov 5 23:30:59.849296 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 5 23:30:59.849302 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Nov 5 23:30:59.849309 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 23:30:59.849316 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 5 23:30:59.849324 kernel: Detected PIPT I-cache on CPU2 Nov 5 23:30:59.849331 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 5 23:30:59.849342 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Nov 5 23:30:59.849351 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 23:30:59.849358 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 5 23:30:59.849365 kernel: Detected PIPT I-cache on CPU3 Nov 5 23:30:59.849372 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 5 23:30:59.849379 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Nov 5 23:30:59.849387 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 23:30:59.849394 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 5 23:30:59.849401 kernel: smp: Brought up 1 node, 4 CPUs Nov 5 23:30:59.849408 kernel: SMP: Total of 4 processors activated. Nov 5 23:30:59.849415 kernel: CPU features: detected: 32-bit EL0 Support Nov 5 23:30:59.849422 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 5 23:30:59.849429 kernel: CPU features: detected: Common not Private translations Nov 5 23:30:59.849436 kernel: CPU features: detected: CRC32 instructions Nov 5 23:30:59.849443 kernel: CPU features: detected: Enhanced Virtualization Traps Nov 5 23:30:59.849451 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 5 23:30:59.849458 kernel: CPU features: detected: LSE atomic instructions Nov 5 23:30:59.849465 kernel: CPU features: detected: Privileged Access Never Nov 5 23:30:59.849472 kernel: CPU features: detected: RAS Extension Support Nov 5 23:30:59.849479 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 5 23:30:59.849486 kernel: CPU: All CPU(s) started at EL1 Nov 5 23:30:59.849493 kernel: alternatives: applying system-wide alternatives Nov 5 23:30:59.849500 kernel: devtmpfs: initialized Nov 5 23:30:59.849507 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 5 23:30:59.849514 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 5 23:30:59.849522 kernel: pinctrl core: initialized pinctrl subsystem Nov 5 23:30:59.849529 kernel: SMBIOS 3.0.0 present. Nov 5 23:30:59.849536 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 5 23:30:59.849543 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 5 23:30:59.849550 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 5 23:30:59.849558 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 5 23:30:59.849565 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 5 23:30:59.849572 kernel: audit: initializing netlink subsys (disabled) Nov 5 23:30:59.849580 kernel: audit: type=2000 audit(0.017:1): state=initialized audit_enabled=0 res=1 Nov 5 23:30:59.849587 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 5 23:30:59.849594 kernel: cpuidle: using governor menu Nov 5 23:30:59.849601 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 5 23:30:59.849608 kernel: ASID allocator initialised with 32768 entries Nov 5 23:30:59.849615 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 5 23:30:59.849622 kernel: Serial: AMBA PL011 UART driver Nov 5 23:30:59.849630 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 5 23:30:59.849637 kernel: Modules: 0 pages in range for non-PLT usage Nov 5 23:30:59.849643 kernel: Modules: 509248 pages in range for PLT usage Nov 5 23:30:59.849652 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 5 23:30:59.849659 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 5 23:30:59.849666 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 5 23:30:59.849673 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 5 23:30:59.849680 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 5 23:30:59.849688 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 5 23:30:59.849694 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 5 23:30:59.849701 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 5 23:30:59.849708 kernel: ACPI: Added _OSI(Module Device) Nov 5 23:30:59.849717 kernel: ACPI: Added _OSI(Processor Device) Nov 5 23:30:59.849724 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 5 23:30:59.849731 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 5 23:30:59.849738 kernel: ACPI: Interpreter enabled Nov 5 23:30:59.849745 kernel: ACPI: Using GIC for interrupt routing Nov 5 23:30:59.849752 kernel: ACPI: MCFG table detected, 1 entries Nov 5 23:30:59.849759 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 5 23:30:59.849766 kernel: printk: console [ttyAMA0] enabled Nov 5 23:30:59.849773 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 5 23:30:59.849920 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 5 23:30:59.849995 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 5 23:30:59.850061 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 5 23:30:59.850139 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 5 23:30:59.850204 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 5 23:30:59.850271 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 5 23:30:59.850279 kernel: PCI host bridge to bus 0000:00 Nov 5 23:30:59.850360 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 5 23:30:59.850421 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 5 23:30:59.850480 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 5 23:30:59.850537 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 5 23:30:59.850616 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 5 23:30:59.850692 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 5 23:30:59.850763 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 5 23:30:59.850830 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 5 23:30:59.850895 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 5 23:30:59.850961 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 5 23:30:59.851028 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 5 23:30:59.851095 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 5 23:30:59.851171 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 5 23:30:59.851250 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 5 23:30:59.851309 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 5 23:30:59.851319 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Nov 5 23:30:59.851326 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Nov 5 23:30:59.851333 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Nov 5 23:30:59.851341 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Nov 5 23:30:59.851348 kernel: iommu: Default domain type: Translated Nov 5 23:30:59.851355 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 5 23:30:59.851365 kernel: efivars: Registered efivars operations Nov 5 23:30:59.851372 kernel: vgaarb: loaded Nov 5 23:30:59.851379 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 5 23:30:59.851386 kernel: VFS: Disk quotas dquot_6.6.0 Nov 5 23:30:59.851394 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 5 23:30:59.851401 kernel: pnp: PnP ACPI init Nov 5 23:30:59.851473 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 5 23:30:59.851483 kernel: pnp: PnP ACPI: found 1 devices Nov 5 23:30:59.851491 kernel: NET: Registered PF_INET protocol family Nov 5 23:30:59.851500 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 5 23:30:59.851508 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 5 23:30:59.851515 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 5 23:30:59.851521 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 5 23:30:59.851529 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 5 23:30:59.851536 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 5 23:30:59.851543 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 23:30:59.851550 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 23:30:59.851557 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 5 23:30:59.851565 kernel: PCI: CLS 0 bytes, default 64 Nov 5 23:30:59.851573 kernel: kvm [1]: HYP mode not available Nov 5 23:30:59.851580 kernel: Initialise system trusted keyrings Nov 5 23:30:59.851587 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 5 23:30:59.851594 kernel: Key type asymmetric registered Nov 5 23:30:59.851601 kernel: Asymmetric key parser 'x509' registered Nov 5 23:30:59.851608 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 5 23:30:59.851615 kernel: io scheduler mq-deadline registered Nov 5 23:30:59.851622 kernel: io scheduler kyber registered Nov 5 23:30:59.851631 kernel: io scheduler bfq registered Nov 5 23:30:59.851638 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 5 23:30:59.851645 kernel: ACPI: button: Power Button [PWRB] Nov 5 23:30:59.851652 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Nov 5 23:30:59.851717 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 5 23:30:59.851727 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 5 23:30:59.851734 kernel: thunder_xcv, ver 1.0 Nov 5 23:30:59.851741 kernel: thunder_bgx, ver 1.0 Nov 5 23:30:59.851748 kernel: nicpf, ver 1.0 Nov 5 23:30:59.851757 kernel: nicvf, ver 1.0 Nov 5 23:30:59.851834 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 5 23:30:59.851896 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-05T23:30:59 UTC (1762385459) Nov 5 23:30:59.851906 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 5 23:30:59.851913 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 5 23:30:59.851920 kernel: watchdog: Delayed init of the lockup detector failed: -19 Nov 5 23:30:59.851927 kernel: watchdog: Hard watchdog permanently disabled Nov 5 23:30:59.851934 kernel: NET: Registered PF_INET6 protocol family Nov 5 23:30:59.851943 kernel: Segment Routing with IPv6 Nov 5 23:30:59.851951 kernel: In-situ OAM (IOAM) with IPv6 Nov 5 23:30:59.851958 kernel: NET: Registered PF_PACKET protocol family Nov 5 23:30:59.851965 kernel: Key type dns_resolver registered Nov 5 23:30:59.851972 kernel: registered taskstats version 1 Nov 5 23:30:59.851979 kernel: Loading compiled-in X.509 certificates Nov 5 23:30:59.851986 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: 5a387f2f4b373e0385aa30cfcbf1acc42ad11535' Nov 5 23:30:59.851993 kernel: Key type .fscrypt registered Nov 5 23:30:59.852000 kernel: Key type fscrypt-provisioning registered Nov 5 23:30:59.852009 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 5 23:30:59.852016 kernel: ima: Allocated hash algorithm: sha1 Nov 5 23:30:59.852023 kernel: ima: No architecture policies found Nov 5 23:30:59.852030 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 5 23:30:59.852037 kernel: clk: Disabling unused clocks Nov 5 23:30:59.852044 kernel: Freeing unused kernel memory: 38400K Nov 5 23:30:59.852051 kernel: Run /init as init process Nov 5 23:30:59.852057 kernel: with arguments: Nov 5 23:30:59.852064 kernel: /init Nov 5 23:30:59.852073 kernel: with environment: Nov 5 23:30:59.852080 kernel: HOME=/ Nov 5 23:30:59.852086 kernel: TERM=linux Nov 5 23:30:59.852095 systemd[1]: Successfully made /usr/ read-only. Nov 5 23:30:59.852118 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 23:30:59.852126 systemd[1]: Detected virtualization kvm. Nov 5 23:30:59.852134 systemd[1]: Detected architecture arm64. Nov 5 23:30:59.852141 systemd[1]: Running in initrd. Nov 5 23:30:59.852151 systemd[1]: No hostname configured, using default hostname. Nov 5 23:30:59.852159 systemd[1]: Hostname set to . Nov 5 23:30:59.852166 systemd[1]: Initializing machine ID from VM UUID. Nov 5 23:30:59.852174 systemd[1]: Queued start job for default target initrd.target. Nov 5 23:30:59.852182 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 23:30:59.852189 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 23:30:59.852197 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 5 23:30:59.852205 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 23:30:59.852222 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 5 23:30:59.852231 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 5 23:30:59.852240 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 5 23:30:59.852248 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 5 23:30:59.852256 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 23:30:59.852264 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 23:30:59.852275 systemd[1]: Reached target paths.target - Path Units. Nov 5 23:30:59.852283 systemd[1]: Reached target slices.target - Slice Units. Nov 5 23:30:59.852290 systemd[1]: Reached target swap.target - Swaps. Nov 5 23:30:59.852298 systemd[1]: Reached target timers.target - Timer Units. Nov 5 23:30:59.852306 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 23:30:59.852313 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 23:30:59.852321 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 5 23:30:59.852329 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 5 23:30:59.852336 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 23:30:59.852346 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 23:30:59.852354 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 23:30:59.852361 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 23:30:59.852369 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 5 23:30:59.852376 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 23:30:59.852384 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 5 23:30:59.852392 systemd[1]: Starting systemd-fsck-usr.service... Nov 5 23:30:59.852399 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 23:30:59.852407 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 23:30:59.852416 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 23:30:59.852424 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 5 23:30:59.852432 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 23:30:59.852440 systemd[1]: Finished systemd-fsck-usr.service. Nov 5 23:30:59.852468 systemd-journald[238]: Collecting audit messages is disabled. Nov 5 23:30:59.852487 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 5 23:30:59.852495 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 23:30:59.852504 systemd-journald[238]: Journal started Nov 5 23:30:59.852523 systemd-journald[238]: Runtime Journal (/run/log/journal/fccfd64c26084e6086e0fb6dcd53d715) is 5.9M, max 47.3M, 41.4M free. Nov 5 23:30:59.845109 systemd-modules-load[239]: Inserted module 'overlay' Nov 5 23:30:59.855087 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 23:30:59.856548 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 5 23:30:59.860936 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 5 23:30:59.860955 kernel: Bridge firewalling registered Nov 5 23:30:59.861488 systemd-modules-load[239]: Inserted module 'br_netfilter' Nov 5 23:30:59.862084 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 23:30:59.863989 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 23:30:59.868427 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 23:30:59.870034 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 23:30:59.872616 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 23:30:59.876373 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 23:30:59.880457 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 23:30:59.885838 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 23:30:59.897379 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 23:30:59.898625 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 23:30:59.901381 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 5 23:30:59.917014 dracut-cmdline[280]: dracut-dracut-053 Nov 5 23:30:59.919558 dracut-cmdline[280]: Using kernel command line parameters: rd.driver.pre=btrfs 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=6e2597b977e57750cfe5ec10206cda05956d53bb22ea4d0b8af9eaf567cda532 Nov 5 23:30:59.928287 systemd-resolved[275]: Positive Trust Anchors: Nov 5 23:30:59.928305 systemd-resolved[275]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 23:30:59.928335 systemd-resolved[275]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 23:30:59.932984 systemd-resolved[275]: Defaulting to hostname 'linux'. Nov 5 23:30:59.933978 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 23:30:59.937929 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 23:30:59.989225 kernel: SCSI subsystem initialized Nov 5 23:30:59.993239 kernel: Loading iSCSI transport class v2.0-870. Nov 5 23:31:00.001245 kernel: iscsi: registered transport (tcp) Nov 5 23:31:00.014307 kernel: iscsi: registered transport (qla4xxx) Nov 5 23:31:00.014335 kernel: QLogic iSCSI HBA Driver Nov 5 23:31:00.057523 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 5 23:31:00.067440 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 5 23:31:00.082676 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 5 23:31:00.082729 kernel: device-mapper: uevent: version 1.0.3 Nov 5 23:31:00.082754 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 5 23:31:00.130256 kernel: raid6: neonx8 gen() 15783 MB/s Nov 5 23:31:00.147238 kernel: raid6: neonx4 gen() 15826 MB/s Nov 5 23:31:00.164248 kernel: raid6: neonx2 gen() 13199 MB/s Nov 5 23:31:00.181246 kernel: raid6: neonx1 gen() 10511 MB/s Nov 5 23:31:00.198243 kernel: raid6: int64x8 gen() 6789 MB/s Nov 5 23:31:00.215242 kernel: raid6: int64x4 gen() 7349 MB/s Nov 5 23:31:00.232242 kernel: raid6: int64x2 gen() 6106 MB/s Nov 5 23:31:00.249415 kernel: raid6: int64x1 gen() 5037 MB/s Nov 5 23:31:00.249445 kernel: raid6: using algorithm neonx4 gen() 15826 MB/s Nov 5 23:31:00.267426 kernel: raid6: .... xor() 12422 MB/s, rmw enabled Nov 5 23:31:00.267463 kernel: raid6: using neon recovery algorithm Nov 5 23:31:00.272245 kernel: xor: measuring software checksum speed Nov 5 23:31:00.273495 kernel: 8regs : 18427 MB/sec Nov 5 23:31:00.273510 kernel: 32regs : 21676 MB/sec Nov 5 23:31:00.274837 kernel: arm64_neon : 27841 MB/sec Nov 5 23:31:00.274856 kernel: xor: using function: arm64_neon (27841 MB/sec) Nov 5 23:31:00.322235 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 5 23:31:00.332271 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 5 23:31:00.344401 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 23:31:00.357938 systemd-udevd[462]: Using default interface naming scheme 'v255'. Nov 5 23:31:00.361646 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 23:31:00.377433 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 5 23:31:00.388768 dracut-pre-trigger[470]: rd.md=0: removing MD RAID activation Nov 5 23:31:00.414127 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 23:31:00.429522 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 23:31:00.469569 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 23:31:00.479455 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 5 23:31:00.490822 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 5 23:31:00.492368 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 23:31:00.495798 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 23:31:00.497085 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 23:31:00.505361 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 5 23:31:00.515854 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 5 23:31:00.532368 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Nov 5 23:31:00.539289 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 23:31:00.542554 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Nov 5 23:31:00.539404 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 23:31:00.547558 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 5 23:31:00.547577 kernel: GPT:9289727 != 19775487 Nov 5 23:31:00.547586 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 5 23:31:00.547595 kernel: GPT:9289727 != 19775487 Nov 5 23:31:00.547610 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 5 23:31:00.547619 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 23:31:00.548180 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 23:31:00.549902 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 23:31:00.550035 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 23:31:00.554131 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 23:31:00.569537 kernel: BTRFS: device fsid 5c7a645d-f748-4312-b823-18c9d52f0ddc devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (513) Nov 5 23:31:00.569573 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by (udev-worker) (517) Nov 5 23:31:00.571836 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 23:31:00.583232 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 23:31:00.596077 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 5 23:31:00.608034 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 5 23:31:00.614287 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 5 23:31:00.615561 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 5 23:31:00.624033 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 5 23:31:00.641369 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 5 23:31:00.643187 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 5 23:31:00.649651 disk-uuid[554]: Primary Header is updated. Nov 5 23:31:00.649651 disk-uuid[554]: Secondary Entries is updated. Nov 5 23:31:00.649651 disk-uuid[554]: Secondary Header is updated. Nov 5 23:31:00.653810 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 23:31:00.668666 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 23:31:01.660262 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 23:31:01.661040 disk-uuid[555]: The operation has completed successfully. Nov 5 23:31:01.682621 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 5 23:31:01.682725 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 5 23:31:01.725428 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 5 23:31:01.728242 sh[575]: Success Nov 5 23:31:01.738256 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 5 23:31:01.769355 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 5 23:31:01.785665 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 5 23:31:01.787784 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 5 23:31:01.798689 kernel: BTRFS info (device dm-0): first mount of filesystem 5c7a645d-f748-4312-b823-18c9d52f0ddc Nov 5 23:31:01.798721 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 5 23:31:01.798732 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 5 23:31:01.800766 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 5 23:31:01.800785 kernel: BTRFS info (device dm-0): using free space tree Nov 5 23:31:01.805622 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 5 23:31:01.807024 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 5 23:31:01.816439 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 5 23:31:01.818039 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 5 23:31:01.833848 kernel: BTRFS info (device vda6): first mount of filesystem a89a754e-6e73-4721-be75-55130c40eae4 Nov 5 23:31:01.833899 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 23:31:01.833910 kernel: BTRFS info (device vda6): using free space tree Nov 5 23:31:01.837227 kernel: BTRFS info (device vda6): auto enabling async discard Nov 5 23:31:01.841242 kernel: BTRFS info (device vda6): last unmount of filesystem a89a754e-6e73-4721-be75-55130c40eae4 Nov 5 23:31:01.845026 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 5 23:31:01.851483 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 5 23:31:01.911121 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 23:31:01.919490 ignition[669]: Ignition 2.20.0 Nov 5 23:31:01.919500 ignition[669]: Stage: fetch-offline Nov 5 23:31:01.921935 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 23:31:01.919534 ignition[669]: no configs at "/usr/lib/ignition/base.d" Nov 5 23:31:01.919543 ignition[669]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 23:31:01.919698 ignition[669]: parsed url from cmdline: "" Nov 5 23:31:01.919702 ignition[669]: no config URL provided Nov 5 23:31:01.919706 ignition[669]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 23:31:01.919714 ignition[669]: no config at "/usr/lib/ignition/user.ign" Nov 5 23:31:01.919735 ignition[669]: op(1): [started] loading QEMU firmware config module Nov 5 23:31:01.919739 ignition[669]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 5 23:31:01.926764 ignition[669]: op(1): [finished] loading QEMU firmware config module Nov 5 23:31:01.937672 ignition[669]: parsing config with SHA512: b252a63cc503f277e913ed16d582f32e569dca92f528494403128da15a648f027d387259e68cd945a10e29566fe21969f9a26c74950d32fc6a82855c7ec61377 Nov 5 23:31:01.941177 unknown[669]: fetched base config from "system" Nov 5 23:31:01.941187 unknown[669]: fetched user config from "qemu" Nov 5 23:31:01.941546 ignition[669]: fetch-offline: fetch-offline passed Nov 5 23:31:01.941625 ignition[669]: Ignition finished successfully Nov 5 23:31:01.944496 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 23:31:01.947959 systemd-networkd[765]: lo: Link UP Nov 5 23:31:01.947972 systemd-networkd[765]: lo: Gained carrier Nov 5 23:31:01.948877 systemd-networkd[765]: Enumeration completed Nov 5 23:31:01.949298 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 5 23:31:01.949301 systemd-networkd[765]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 23:31:01.949381 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 23:31:01.951092 systemd[1]: Reached target network.target - Network. Nov 5 23:31:01.951311 systemd-networkd[765]: eth0: Link UP Nov 5 23:31:01.951315 systemd-networkd[765]: eth0: Gained carrier Nov 5 23:31:01.951321 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 5 23:31:01.952901 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 5 23:31:01.960387 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 5 23:31:01.970269 systemd-networkd[765]: eth0: DHCPv4 address 10.0.0.84/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 5 23:31:01.979031 ignition[770]: Ignition 2.20.0 Nov 5 23:31:01.979051 ignition[770]: Stage: kargs Nov 5 23:31:01.979259 ignition[770]: no configs at "/usr/lib/ignition/base.d" Nov 5 23:31:01.979270 ignition[770]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 23:31:01.982446 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 5 23:31:01.979960 ignition[770]: kargs: kargs passed Nov 5 23:31:01.980004 ignition[770]: Ignition finished successfully Nov 5 23:31:01.999387 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 5 23:31:02.009251 ignition[780]: Ignition 2.20.0 Nov 5 23:31:02.009261 ignition[780]: Stage: disks Nov 5 23:31:02.009435 ignition[780]: no configs at "/usr/lib/ignition/base.d" Nov 5 23:31:02.009446 ignition[780]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 23:31:02.011878 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 5 23:31:02.010119 ignition[780]: disks: disks passed Nov 5 23:31:02.013353 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 5 23:31:02.010173 ignition[780]: Ignition finished successfully Nov 5 23:31:02.015207 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 5 23:31:02.017248 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 23:31:02.018787 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 23:31:02.020680 systemd[1]: Reached target basic.target - Basic System. Nov 5 23:31:02.035356 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 5 23:31:02.045667 systemd-fsck[793]: ROOT: clean, 14/553520 files, 52654/553472 blocks Nov 5 23:31:02.049159 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 5 23:31:02.058421 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 5 23:31:02.102242 kernel: EXT4-fs (vda9): mounted filesystem aac3d387-9665-4dc3-844b-8d8940ef87ee r/w with ordered data mode. Quota mode: none. Nov 5 23:31:02.103121 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 5 23:31:02.104834 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 5 23:31:02.121331 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 23:31:02.123607 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 5 23:31:02.125920 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 5 23:31:02.125989 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 5 23:31:02.134506 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by mount (801) Nov 5 23:31:02.134536 kernel: BTRFS info (device vda6): first mount of filesystem a89a754e-6e73-4721-be75-55130c40eae4 Nov 5 23:31:02.126019 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 23:31:02.138998 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 23:31:02.139019 kernel: BTRFS info (device vda6): using free space tree Nov 5 23:31:02.131871 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 5 23:31:02.139040 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 5 23:31:02.144218 kernel: BTRFS info (device vda6): auto enabling async discard Nov 5 23:31:02.144884 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 23:31:02.177351 initrd-setup-root[825]: cut: /sysroot/etc/passwd: No such file or directory Nov 5 23:31:02.180789 initrd-setup-root[832]: cut: /sysroot/etc/group: No such file or directory Nov 5 23:31:02.185487 initrd-setup-root[839]: cut: /sysroot/etc/shadow: No such file or directory Nov 5 23:31:02.189465 initrd-setup-root[846]: cut: /sysroot/etc/gshadow: No such file or directory Nov 5 23:31:02.269098 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 5 23:31:02.281450 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 5 23:31:02.284445 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 5 23:31:02.289234 kernel: BTRFS info (device vda6): last unmount of filesystem a89a754e-6e73-4721-be75-55130c40eae4 Nov 5 23:31:02.306879 ignition[914]: INFO : Ignition 2.20.0 Nov 5 23:31:02.306879 ignition[914]: INFO : Stage: mount Nov 5 23:31:02.309606 ignition[914]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 23:31:02.309606 ignition[914]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 23:31:02.309606 ignition[914]: INFO : mount: mount passed Nov 5 23:31:02.309606 ignition[914]: INFO : Ignition finished successfully Nov 5 23:31:02.307292 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 5 23:31:02.310243 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 5 23:31:02.320443 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 5 23:31:02.925611 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 5 23:31:02.933520 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 5 23:31:02.941947 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (929) Nov 5 23:31:02.941981 kernel: BTRFS info (device vda6): first mount of filesystem a89a754e-6e73-4721-be75-55130c40eae4 Nov 5 23:31:02.941993 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Nov 5 23:31:02.943673 kernel: BTRFS info (device vda6): using free space tree Nov 5 23:31:02.946233 kernel: BTRFS info (device vda6): auto enabling async discard Nov 5 23:31:02.947541 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 5 23:31:02.969714 ignition[946]: INFO : Ignition 2.20.0 Nov 5 23:31:02.969714 ignition[946]: INFO : Stage: files Nov 5 23:31:02.971650 ignition[946]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 23:31:02.971650 ignition[946]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 23:31:02.971650 ignition[946]: DEBUG : files: compiled without relabeling support, skipping Nov 5 23:31:02.978470 ignition[946]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 5 23:31:02.978470 ignition[946]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 5 23:31:02.978470 ignition[946]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 5 23:31:02.978470 ignition[946]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 5 23:31:02.978470 ignition[946]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 5 23:31:02.978470 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 5 23:31:02.978470 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 5 23:31:02.978470 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 23:31:02.978470 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 5 23:31:02.978470 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 5 23:31:02.978470 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 5 23:31:02.978470 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 5 23:31:02.976805 unknown[946]: wrote ssh authorized keys file for user: core Nov 5 23:31:03.005677 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Nov 5 23:31:03.046362 systemd-networkd[765]: eth0: Gained IPv6LL Nov 5 23:31:03.341994 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 5 23:31:03.585092 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Nov 5 23:31:03.585092 ignition[946]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Nov 5 23:31:03.589734 ignition[946]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 5 23:31:03.589734 ignition[946]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 5 23:31:03.589734 ignition[946]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Nov 5 23:31:03.589734 ignition[946]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Nov 5 23:31:03.603788 ignition[946]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 5 23:31:03.607605 ignition[946]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 5 23:31:03.611909 ignition[946]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Nov 5 23:31:03.611909 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 5 23:31:03.611909 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 5 23:31:03.611909 ignition[946]: INFO : files: files passed Nov 5 23:31:03.611909 ignition[946]: INFO : Ignition finished successfully Nov 5 23:31:03.610651 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 5 23:31:03.623414 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 5 23:31:03.625273 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 5 23:31:03.628237 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 5 23:31:03.629256 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 5 23:31:03.632585 initrd-setup-root-after-ignition[974]: grep: /sysroot/oem/oem-release: No such file or directory Nov 5 23:31:03.636105 initrd-setup-root-after-ignition[976]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 23:31:03.636105 initrd-setup-root-after-ignition[976]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 5 23:31:03.639249 initrd-setup-root-after-ignition[980]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 5 23:31:03.640121 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 23:31:03.643523 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 5 23:31:03.650407 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 5 23:31:03.668260 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 5 23:31:03.668371 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 5 23:31:03.670614 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 5 23:31:03.672660 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 5 23:31:03.674480 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 5 23:31:03.675223 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 5 23:31:03.690171 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 23:31:03.703407 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 5 23:31:03.711817 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 5 23:31:03.713165 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 23:31:03.715353 systemd[1]: Stopped target timers.target - Timer Units. Nov 5 23:31:03.717183 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 5 23:31:03.717329 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 5 23:31:03.720005 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 5 23:31:03.722185 systemd[1]: Stopped target basic.target - Basic System. Nov 5 23:31:03.724031 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 5 23:31:03.725789 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 5 23:31:03.727832 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 5 23:31:03.729964 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 5 23:31:03.731999 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 5 23:31:03.734148 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 5 23:31:03.736325 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 5 23:31:03.738304 systemd[1]: Stopped target swap.target - Swaps. Nov 5 23:31:03.740022 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 5 23:31:03.740163 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 5 23:31:03.742652 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 5 23:31:03.744690 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 23:31:03.746856 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 5 23:31:03.750293 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 23:31:03.751678 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 5 23:31:03.751799 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 5 23:31:03.754947 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 5 23:31:03.755064 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 5 23:31:03.757225 systemd[1]: Stopped target paths.target - Path Units. Nov 5 23:31:03.758826 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 5 23:31:03.762284 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 23:31:03.763595 systemd[1]: Stopped target slices.target - Slice Units. Nov 5 23:31:03.765929 systemd[1]: Stopped target sockets.target - Socket Units. Nov 5 23:31:03.767648 systemd[1]: iscsid.socket: Deactivated successfully. Nov 5 23:31:03.767737 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 5 23:31:03.769383 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 5 23:31:03.769469 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 5 23:31:03.771145 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 5 23:31:03.771280 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 5 23:31:03.773222 systemd[1]: ignition-files.service: Deactivated successfully. Nov 5 23:31:03.773330 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 5 23:31:03.789397 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 5 23:31:03.790370 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 5 23:31:03.790503 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 23:31:03.796410 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 5 23:31:03.797323 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 5 23:31:03.797455 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 23:31:03.803091 ignition[1001]: INFO : Ignition 2.20.0 Nov 5 23:31:03.803091 ignition[1001]: INFO : Stage: umount Nov 5 23:31:03.803091 ignition[1001]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 5 23:31:03.803091 ignition[1001]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 5 23:31:03.803091 ignition[1001]: INFO : umount: umount passed Nov 5 23:31:03.803091 ignition[1001]: INFO : Ignition finished successfully Nov 5 23:31:03.799456 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 5 23:31:03.799562 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 5 23:31:03.804808 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 5 23:31:03.806239 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 5 23:31:03.810108 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 5 23:31:03.810669 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 5 23:31:03.810782 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 5 23:31:03.814809 systemd[1]: Stopped target network.target - Network. Nov 5 23:31:03.816040 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 5 23:31:03.816095 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 5 23:31:03.818097 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 5 23:31:03.818144 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 5 23:31:03.819855 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 5 23:31:03.819898 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 5 23:31:03.821637 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 5 23:31:03.821681 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 5 23:31:03.823558 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 5 23:31:03.825592 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 5 23:31:03.831939 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 5 23:31:03.832072 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 5 23:31:03.835248 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 5 23:31:03.835498 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 5 23:31:03.835535 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 23:31:03.839481 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 5 23:31:03.840653 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 5 23:31:03.840747 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 5 23:31:03.845616 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 5 23:31:03.845646 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 5 23:31:03.855327 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 5 23:31:03.856253 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 5 23:31:03.856309 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 5 23:31:03.858552 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 5 23:31:03.858595 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 5 23:31:03.861850 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 5 23:31:03.861893 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 5 23:31:03.864072 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 23:31:03.868077 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 5 23:31:03.868186 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 5 23:31:03.870890 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 5 23:31:03.871042 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 5 23:31:03.876584 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 5 23:31:03.876682 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 5 23:31:03.878755 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 5 23:31:03.878875 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 23:31:03.881436 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 5 23:31:03.881489 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 5 23:31:03.883356 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 5 23:31:03.883387 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 23:31:03.885518 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 5 23:31:03.885569 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 5 23:31:03.888246 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 5 23:31:03.888293 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 5 23:31:03.891264 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 23:31:03.891313 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 5 23:31:03.906515 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 5 23:31:03.907654 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 5 23:31:03.907718 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 23:31:03.911096 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 23:31:03.911141 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 23:31:03.915060 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 5 23:31:03.915168 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 5 23:31:03.917534 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 5 23:31:03.919946 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 5 23:31:03.925007 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 5 23:31:03.925068 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Nov 5 23:31:03.925099 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 5 23:31:03.925132 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 5 23:31:03.929708 systemd[1]: Switching root. Nov 5 23:31:03.960051 systemd-journald[238]: Journal stopped Nov 5 23:31:04.698891 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Nov 5 23:31:04.698997 kernel: SELinux: policy capability network_peer_controls=1 Nov 5 23:31:04.699012 kernel: SELinux: policy capability open_perms=1 Nov 5 23:31:04.699023 kernel: SELinux: policy capability extended_socket_class=1 Nov 5 23:31:04.699037 kernel: SELinux: policy capability always_check_network=0 Nov 5 23:31:04.699048 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 5 23:31:04.699058 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 5 23:31:04.699071 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 5 23:31:04.699080 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 5 23:31:04.699090 kernel: audit: type=1403 audit(1762385464.101:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 5 23:31:04.699100 systemd[1]: Successfully loaded SELinux policy in 37.363ms. Nov 5 23:31:04.699116 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.458ms. Nov 5 23:31:04.699128 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 5 23:31:04.699142 systemd[1]: Detected virtualization kvm. Nov 5 23:31:04.699162 systemd[1]: Detected architecture arm64. Nov 5 23:31:04.699180 systemd[1]: Detected first boot. Nov 5 23:31:04.699191 systemd[1]: Initializing machine ID from VM UUID. Nov 5 23:31:04.699202 kernel: NET: Registered PF_VSOCK protocol family Nov 5 23:31:04.699223 zram_generator::config[1048]: No configuration found. Nov 5 23:31:04.699236 systemd[1]: Populated /etc with preset unit settings. Nov 5 23:31:04.699247 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 5 23:31:04.699258 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 5 23:31:04.699269 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 5 23:31:04.699279 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 5 23:31:04.699289 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 5 23:31:04.699300 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 5 23:31:04.699315 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 5 23:31:04.699326 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 5 23:31:04.699336 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 5 23:31:04.699347 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 5 23:31:04.699357 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 5 23:31:04.699367 systemd[1]: Created slice user.slice - User and Session Slice. Nov 5 23:31:04.699377 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 5 23:31:04.699388 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 5 23:31:04.699398 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 5 23:31:04.699410 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 5 23:31:04.699421 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 5 23:31:04.699432 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 5 23:31:04.699444 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 5 23:31:04.699455 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 5 23:31:04.699465 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 5 23:31:04.699475 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 5 23:31:04.699486 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 5 23:31:04.699498 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 5 23:31:04.699510 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 5 23:31:04.699521 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 5 23:31:04.699532 systemd[1]: Reached target slices.target - Slice Units. Nov 5 23:31:04.699544 systemd[1]: Reached target swap.target - Swaps. Nov 5 23:31:04.699558 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 5 23:31:04.699569 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 5 23:31:04.699580 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 5 23:31:04.699590 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 5 23:31:04.699602 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 5 23:31:04.699613 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 5 23:31:04.699624 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 5 23:31:04.699635 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 5 23:31:04.699646 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 5 23:31:04.699657 systemd[1]: Mounting media.mount - External Media Directory... Nov 5 23:31:04.699668 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 5 23:31:04.699679 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 5 23:31:04.699689 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 5 23:31:04.699702 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 5 23:31:04.699713 systemd[1]: Reached target machines.target - Containers. Nov 5 23:31:04.699724 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 5 23:31:04.699735 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 23:31:04.699813 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 5 23:31:04.699876 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 5 23:31:04.699893 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 23:31:04.699906 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 23:31:04.700024 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 23:31:04.700124 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 5 23:31:04.700136 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 23:31:04.700147 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 5 23:31:04.700191 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 5 23:31:04.700533 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 5 23:31:04.700554 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 5 23:31:04.700566 systemd[1]: Stopped systemd-fsck-usr.service. Nov 5 23:31:04.700578 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 23:31:04.700594 kernel: fuse: init (API version 7.39) Nov 5 23:31:04.700604 kernel: loop: module loaded Nov 5 23:31:04.700614 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 5 23:31:04.700625 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 5 23:31:04.700635 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 5 23:31:04.700645 kernel: ACPI: bus type drm_connector registered Nov 5 23:31:04.700655 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 5 23:31:04.700670 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 5 23:31:04.700707 systemd-journald[1119]: Collecting audit messages is disabled. Nov 5 23:31:04.700730 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 5 23:31:04.700740 systemd[1]: verity-setup.service: Deactivated successfully. Nov 5 23:31:04.700755 systemd[1]: Stopped verity-setup.service. Nov 5 23:31:04.700766 systemd-journald[1119]: Journal started Nov 5 23:31:04.700788 systemd-journald[1119]: Runtime Journal (/run/log/journal/fccfd64c26084e6086e0fb6dcd53d715) is 5.9M, max 47.3M, 41.4M free. Nov 5 23:31:04.485454 systemd[1]: Queued start job for default target multi-user.target. Nov 5 23:31:04.499153 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 5 23:31:04.499577 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 5 23:31:04.707065 systemd[1]: Started systemd-journald.service - Journal Service. Nov 5 23:31:04.707702 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 5 23:31:04.709302 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 5 23:31:04.710774 systemd[1]: Mounted media.mount - External Media Directory. Nov 5 23:31:04.712223 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 5 23:31:04.714853 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 5 23:31:04.716180 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 5 23:31:04.718266 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 5 23:31:04.719826 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 5 23:31:04.721494 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 5 23:31:04.721679 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 5 23:31:04.724573 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 23:31:04.724748 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 23:31:04.726357 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 23:31:04.726521 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 23:31:04.727953 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 23:31:04.728118 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 23:31:04.729708 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 5 23:31:04.729867 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 5 23:31:04.731456 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 23:31:04.731625 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 23:31:04.733176 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 5 23:31:04.734806 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 5 23:31:04.737269 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 5 23:31:04.738940 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 5 23:31:04.751000 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 5 23:31:04.758313 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 5 23:31:04.760503 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 5 23:31:04.761785 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 5 23:31:04.761824 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 5 23:31:04.763858 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 5 23:31:04.766318 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 5 23:31:04.768480 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 5 23:31:04.769673 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 23:31:04.771112 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 5 23:31:04.773709 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 5 23:31:04.775677 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 23:31:04.779385 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 5 23:31:04.780498 systemd-journald[1119]: Time spent on flushing to /var/log/journal/fccfd64c26084e6086e0fb6dcd53d715 is 17.004ms for 845 entries. Nov 5 23:31:04.780498 systemd-journald[1119]: System Journal (/var/log/journal/fccfd64c26084e6086e0fb6dcd53d715) is 8M, max 195.6M, 187.6M free. Nov 5 23:31:04.821406 systemd-journald[1119]: Received client request to flush runtime journal. Nov 5 23:31:04.821465 kernel: loop0: detected capacity change from 0 to 123192 Nov 5 23:31:04.780559 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 23:31:04.781527 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 5 23:31:04.784701 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 5 23:31:04.787252 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 5 23:31:04.795667 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 5 23:31:04.797326 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 5 23:31:04.798897 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 5 23:31:04.803061 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 5 23:31:04.809444 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 5 23:31:04.815687 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 5 23:31:04.821490 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 5 23:31:04.828247 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 5 23:31:04.833404 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 5 23:31:04.839378 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 5 23:31:04.844297 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 5 23:31:04.846584 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 5 23:31:04.852395 kernel: loop1: detected capacity change from 0 to 207008 Nov 5 23:31:04.865034 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 5 23:31:04.867155 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 5 23:31:04.869297 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 5 23:31:04.871683 udevadm[1177]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 5 23:31:04.885276 systemd-tmpfiles[1182]: ACLs are not supported, ignoring. Nov 5 23:31:04.885296 systemd-tmpfiles[1182]: ACLs are not supported, ignoring. Nov 5 23:31:04.889292 kernel: loop2: detected capacity change from 0 to 113512 Nov 5 23:31:04.889846 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 5 23:31:04.939248 kernel: loop3: detected capacity change from 0 to 123192 Nov 5 23:31:04.944512 kernel: loop4: detected capacity change from 0 to 207008 Nov 5 23:31:04.952447 kernel: loop5: detected capacity change from 0 to 113512 Nov 5 23:31:04.956763 (sd-merge)[1192]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Nov 5 23:31:04.957444 (sd-merge)[1192]: Merged extensions into '/usr'. Nov 5 23:31:04.964877 systemd[1]: Reload requested from client PID 1164 ('systemd-sysext') (unit systemd-sysext.service)... Nov 5 23:31:04.964893 systemd[1]: Reloading... Nov 5 23:31:05.019273 zram_generator::config[1220]: No configuration found. Nov 5 23:31:05.037472 ldconfig[1159]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 5 23:31:05.109188 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 5 23:31:05.161151 systemd[1]: Reloading finished in 195 ms. Nov 5 23:31:05.181861 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 5 23:31:05.185413 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 5 23:31:05.200381 systemd[1]: Starting ensure-sysext.service... Nov 5 23:31:05.202112 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 5 23:31:05.210177 systemd[1]: Reload requested from client PID 1254 ('systemctl') (unit ensure-sysext.service)... Nov 5 23:31:05.210196 systemd[1]: Reloading... Nov 5 23:31:05.217421 systemd-tmpfiles[1255]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 5 23:31:05.217888 systemd-tmpfiles[1255]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 5 23:31:05.218660 systemd-tmpfiles[1255]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 5 23:31:05.218950 systemd-tmpfiles[1255]: ACLs are not supported, ignoring. Nov 5 23:31:05.219059 systemd-tmpfiles[1255]: ACLs are not supported, ignoring. Nov 5 23:31:05.221679 systemd-tmpfiles[1255]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 23:31:05.221781 systemd-tmpfiles[1255]: Skipping /boot Nov 5 23:31:05.231032 systemd-tmpfiles[1255]: Detected autofs mount point /boot during canonicalization of boot. Nov 5 23:31:05.231121 systemd-tmpfiles[1255]: Skipping /boot Nov 5 23:31:05.262401 zram_generator::config[1284]: No configuration found. Nov 5 23:31:05.341482 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 5 23:31:05.391362 systemd[1]: Reloading finished in 180 ms. Nov 5 23:31:05.404192 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 5 23:31:05.420302 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 5 23:31:05.427661 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 23:31:05.430189 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 5 23:31:05.432662 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 5 23:31:05.436479 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 5 23:31:05.438922 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 5 23:31:05.442496 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 5 23:31:05.448625 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 23:31:05.449789 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 23:31:05.452537 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 23:31:05.458087 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 23:31:05.461430 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 23:31:05.461547 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 23:31:05.463098 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 5 23:31:05.465542 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 23:31:05.465707 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 23:31:05.469999 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 5 23:31:05.472123 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 23:31:05.472329 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 23:31:05.474071 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 23:31:05.474264 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 23:31:05.481883 systemd-udevd[1325]: Using default interface naming scheme 'v255'. Nov 5 23:31:05.483983 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 23:31:05.495784 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 23:31:05.498293 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 23:31:05.501909 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 23:31:05.502554 augenrules[1357]: No rules Nov 5 23:31:05.503588 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 23:31:05.503764 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 23:31:05.505012 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 5 23:31:05.508723 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 5 23:31:05.511952 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 5 23:31:05.515117 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 23:31:05.515365 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 23:31:05.516985 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 5 23:31:05.518817 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 23:31:05.518967 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 23:31:05.521894 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 23:31:05.522036 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 23:31:05.529264 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 5 23:31:05.533184 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 5 23:31:05.537842 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 23:31:05.538023 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 23:31:05.555585 systemd[1]: Finished ensure-sysext.service. Nov 5 23:31:05.562082 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 5 23:31:05.574237 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1376) Nov 5 23:31:05.574467 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 5 23:31:05.575869 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 5 23:31:05.578787 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 5 23:31:05.583015 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 5 23:31:05.588149 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 5 23:31:05.589880 systemd-resolved[1324]: Positive Trust Anchors: Nov 5 23:31:05.589903 systemd-resolved[1324]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 23:31:05.589934 systemd-resolved[1324]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 5 23:31:05.594796 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 5 23:31:05.596429 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 5 23:31:05.596478 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 5 23:31:05.597606 systemd-resolved[1324]: Defaulting to hostname 'linux'. Nov 5 23:31:05.599248 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 5 23:31:05.602320 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 5 23:31:05.603762 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 5 23:31:05.604163 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 5 23:31:05.606088 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 5 23:31:05.606315 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 5 23:31:05.608103 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 23:31:05.608304 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 5 23:31:05.609623 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 5 23:31:05.609780 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 5 23:31:05.611531 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 5 23:31:05.611776 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 5 23:31:05.612028 augenrules[1395]: /sbin/augenrules: No change Nov 5 23:31:05.618733 augenrules[1424]: No rules Nov 5 23:31:05.623610 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 23:31:05.623827 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 5 23:31:05.635251 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 5 23:31:05.637434 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 5 23:31:05.644419 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 5 23:31:05.645576 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 5 23:31:05.645645 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 5 23:31:05.659789 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 5 23:31:05.673993 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 5 23:31:05.675019 systemd-networkd[1407]: lo: Link UP Nov 5 23:31:05.675033 systemd-networkd[1407]: lo: Gained carrier Nov 5 23:31:05.675791 systemd[1]: Reached target time-set.target - System Time Set. Nov 5 23:31:05.675925 systemd-networkd[1407]: Enumeration completed Nov 5 23:31:05.676921 systemd-networkd[1407]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 5 23:31:05.676933 systemd-networkd[1407]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 5 23:31:05.677235 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 5 23:31:05.677406 systemd-networkd[1407]: eth0: Link UP Nov 5 23:31:05.677410 systemd-networkd[1407]: eth0: Gained carrier Nov 5 23:31:05.677422 systemd-networkd[1407]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 5 23:31:05.678709 systemd[1]: Reached target network.target - Network. Nov 5 23:31:05.686424 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 5 23:31:05.689496 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 5 23:31:05.692288 systemd-networkd[1407]: eth0: DHCPv4 address 10.0.0.84/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 5 23:31:05.692708 systemd-timesyncd[1410]: Network configuration changed, trying to establish connection. Nov 5 23:31:05.693259 systemd-timesyncd[1410]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 5 23:31:05.693311 systemd-timesyncd[1410]: Initial clock synchronization to Wed 2025-11-05 23:31:05.913488 UTC. Nov 5 23:31:05.702289 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 5 23:31:05.715469 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 5 23:31:05.731258 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 5 23:31:05.741532 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 5 23:31:05.751303 lvm[1448]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 5 23:31:05.754261 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 5 23:31:05.775640 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 5 23:31:05.778488 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 5 23:31:05.779742 systemd[1]: Reached target sysinit.target - System Initialization. Nov 5 23:31:05.781021 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 5 23:31:05.782448 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 5 23:31:05.784054 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 5 23:31:05.785382 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 5 23:31:05.786713 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 5 23:31:05.788136 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 5 23:31:05.788175 systemd[1]: Reached target paths.target - Path Units. Nov 5 23:31:05.789050 systemd[1]: Reached target timers.target - Timer Units. Nov 5 23:31:05.791060 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 5 23:31:05.793547 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 5 23:31:05.796682 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 5 23:31:05.798110 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 5 23:31:05.799425 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 5 23:31:05.806123 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 5 23:31:05.807601 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 5 23:31:05.809928 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 5 23:31:05.811606 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 5 23:31:05.812835 systemd[1]: Reached target sockets.target - Socket Units. Nov 5 23:31:05.813821 systemd[1]: Reached target basic.target - Basic System. Nov 5 23:31:05.814805 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 5 23:31:05.814838 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 5 23:31:05.815776 systemd[1]: Starting containerd.service - containerd container runtime... Nov 5 23:31:05.817507 lvm[1455]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 5 23:31:05.818394 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 5 23:31:05.821365 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 5 23:31:05.824450 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 5 23:31:05.826047 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 5 23:31:05.826978 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 5 23:31:05.830629 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 5 23:31:05.832708 jq[1458]: false Nov 5 23:31:05.834414 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 5 23:31:05.839439 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 5 23:31:05.841582 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 5 23:31:05.842015 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 5 23:31:05.842825 systemd[1]: Starting update-engine.service - Update Engine... Nov 5 23:31:05.844738 dbus-daemon[1457]: [system] SELinux support is enabled Nov 5 23:31:05.845584 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 5 23:31:05.847571 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 5 23:31:05.852495 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 5 23:31:05.853615 jq[1470]: true Nov 5 23:31:05.857611 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 5 23:31:05.857827 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 5 23:31:05.858105 systemd[1]: motdgen.service: Deactivated successfully. Nov 5 23:31:05.858306 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 5 23:31:05.859925 extend-filesystems[1459]: Found loop3 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found loop4 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found loop5 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda1 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda2 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda3 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found usr Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda4 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda6 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda7 Nov 5 23:31:05.861146 extend-filesystems[1459]: Found vda9 Nov 5 23:31:05.861146 extend-filesystems[1459]: Checking size of /dev/vda9 Nov 5 23:31:05.897857 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Nov 5 23:31:05.860798 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 5 23:31:05.898001 update_engine[1468]: I20251105 23:31:05.875937 1468 main.cc:92] Flatcar Update Engine starting Nov 5 23:31:05.898001 update_engine[1468]: I20251105 23:31:05.887341 1468 update_check_scheduler.cc:74] Next update check in 7m24s Nov 5 23:31:05.900480 extend-filesystems[1459]: Resized partition /dev/vda9 Nov 5 23:31:05.860954 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 5 23:31:05.906159 extend-filesystems[1489]: resize2fs 1.47.1 (20-May-2024) Nov 5 23:31:05.869652 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 5 23:31:05.908178 jq[1477]: true Nov 5 23:31:05.869710 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 5 23:31:05.873554 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 5 23:31:05.873574 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 5 23:31:05.882702 (ntainerd)[1485]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 5 23:31:05.884492 systemd[1]: Started update-engine.service - Update Engine. Nov 5 23:31:05.888386 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 5 23:31:05.913379 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1385) Nov 5 23:31:05.911442 systemd-logind[1466]: Watching system buttons on /dev/input/event0 (Power Button) Nov 5 23:31:05.919436 systemd-logind[1466]: New seat seat0. Nov 5 23:31:05.925632 systemd[1]: Started systemd-logind.service - User Login Management. Nov 5 23:31:05.941240 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Nov 5 23:31:05.957270 extend-filesystems[1489]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 5 23:31:05.957270 extend-filesystems[1489]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 5 23:31:05.957270 extend-filesystems[1489]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Nov 5 23:31:05.961447 extend-filesystems[1459]: Resized filesystem in /dev/vda9 Nov 5 23:31:05.959905 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 5 23:31:05.960101 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 5 23:31:05.967565 bash[1507]: Updated "/home/core/.ssh/authorized_keys" Nov 5 23:31:05.969401 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 5 23:31:05.972346 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 5 23:31:05.976498 locksmithd[1491]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 5 23:31:06.029771 containerd[1485]: time="2025-11-05T23:31:06.029681768Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 5 23:31:06.052822 containerd[1485]: time="2025-11-05T23:31:06.052780141Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054152 containerd[1485]: time="2025-11-05T23:31:06.054104392Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054152 containerd[1485]: time="2025-11-05T23:31:06.054144507Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 5 23:31:06.054214 containerd[1485]: time="2025-11-05T23:31:06.054161441Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 5 23:31:06.054338 containerd[1485]: time="2025-11-05T23:31:06.054319147Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 5 23:31:06.054359 containerd[1485]: time="2025-11-05T23:31:06.054343521Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054415 containerd[1485]: time="2025-11-05T23:31:06.054400035Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054438 containerd[1485]: time="2025-11-05T23:31:06.054415243Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054617 containerd[1485]: time="2025-11-05T23:31:06.054599912Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054637 containerd[1485]: time="2025-11-05T23:31:06.054619600Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054661 containerd[1485]: time="2025-11-05T23:31:06.054632629Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054661 containerd[1485]: time="2025-11-05T23:31:06.054642329Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054740 containerd[1485]: time="2025-11-05T23:31:06.054725642Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 5 23:31:06.054929 containerd[1485]: time="2025-11-05T23:31:06.054912613Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 5 23:31:06.055052 containerd[1485]: time="2025-11-05T23:31:06.055036822Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 5 23:31:06.055076 containerd[1485]: time="2025-11-05T23:31:06.055054331Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 5 23:31:06.055139 containerd[1485]: time="2025-11-05T23:31:06.055126341Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 5 23:31:06.055183 containerd[1485]: time="2025-11-05T23:31:06.055171141Z" level=info msg="metadata content store policy set" policy=shared Nov 5 23:31:06.059334 containerd[1485]: time="2025-11-05T23:31:06.059295888Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 5 23:31:06.059385 containerd[1485]: time="2025-11-05T23:31:06.059346566Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 5 23:31:06.059385 containerd[1485]: time="2025-11-05T23:31:06.059361486Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 5 23:31:06.059385 containerd[1485]: time="2025-11-05T23:31:06.059376899Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 5 23:31:06.059446 containerd[1485]: time="2025-11-05T23:31:06.059390175Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 5 23:31:06.059561 containerd[1485]: time="2025-11-05T23:31:06.059528029Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 5 23:31:06.059770 containerd[1485]: time="2025-11-05T23:31:06.059743360Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 5 23:31:06.059870 containerd[1485]: time="2025-11-05T23:31:06.059854334Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 5 23:31:06.059898 containerd[1485]: time="2025-11-05T23:31:06.059876488Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 5 23:31:06.059917 containerd[1485]: time="2025-11-05T23:31:06.059896874Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 5 23:31:06.059917 containerd[1485]: time="2025-11-05T23:31:06.059911671Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.059950 containerd[1485]: time="2025-11-05T23:31:06.059924125Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.059950 containerd[1485]: time="2025-11-05T23:31:06.059937565Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.059986 containerd[1485]: time="2025-11-05T23:31:06.059950759Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.059986 containerd[1485]: time="2025-11-05T23:31:06.059964815Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.059986 containerd[1485]: time="2025-11-05T23:31:06.059977105Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.060042 containerd[1485]: time="2025-11-05T23:31:06.059989271Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.060042 containerd[1485]: time="2025-11-05T23:31:06.060000163Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 5 23:31:06.060076 containerd[1485]: time="2025-11-05T23:31:06.060038387Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060121 containerd[1485]: time="2025-11-05T23:31:06.060092723Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060121 containerd[1485]: time="2025-11-05T23:31:06.060114178Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060167 containerd[1485]: time="2025-11-05T23:31:06.060127577Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060167 containerd[1485]: time="2025-11-05T23:31:06.060141305Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060167 containerd[1485]: time="2025-11-05T23:31:06.060154006Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060167 containerd[1485]: time="2025-11-05T23:31:06.060166460Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060247 containerd[1485]: time="2025-11-05T23:31:06.060180187Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060247 containerd[1485]: time="2025-11-05T23:31:06.060192723Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060247 containerd[1485]: time="2025-11-05T23:31:06.060208342Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060300 containerd[1485]: time="2025-11-05T23:31:06.060269912Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060300 containerd[1485]: time="2025-11-05T23:31:06.060283517Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060300 containerd[1485]: time="2025-11-05T23:31:06.060295888Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060357 containerd[1485]: time="2025-11-05T23:31:06.060311754Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 5 23:31:06.060357 containerd[1485]: time="2025-11-05T23:31:06.060335140Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060357 containerd[1485]: time="2025-11-05T23:31:06.060347923Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.060411 containerd[1485]: time="2025-11-05T23:31:06.060358239Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 5 23:31:06.061104 containerd[1485]: time="2025-11-05T23:31:06.061061734Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 5 23:31:06.061104 containerd[1485]: time="2025-11-05T23:31:06.061096505Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 5 23:31:06.061158 containerd[1485]: time="2025-11-05T23:31:06.061108425Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 5 23:31:06.061158 containerd[1485]: time="2025-11-05T23:31:06.061121002Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 5 23:31:06.061158 containerd[1485]: time="2025-11-05T23:31:06.061131195Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.061158 containerd[1485]: time="2025-11-05T23:31:06.061143485Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 5 23:31:06.061158 containerd[1485]: time="2025-11-05T23:31:06.061153061Z" level=info msg="NRI interface is disabled by configuration." Nov 5 23:31:06.061260 containerd[1485]: time="2025-11-05T23:31:06.061162432Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 5 23:31:06.061580 containerd[1485]: time="2025-11-05T23:31:06.061518125Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 5 23:31:06.061580 containerd[1485]: time="2025-11-05T23:31:06.061572338Z" level=info msg="Connect containerd service" Nov 5 23:31:06.061706 containerd[1485]: time="2025-11-05T23:31:06.061621126Z" level=info msg="using legacy CRI server" Nov 5 23:31:06.061706 containerd[1485]: time="2025-11-05T23:31:06.061628483Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 5 23:31:06.061896 containerd[1485]: time="2025-11-05T23:31:06.061860912Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 5 23:31:06.062537 containerd[1485]: time="2025-11-05T23:31:06.062511056Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 5 23:31:06.062747 containerd[1485]: time="2025-11-05T23:31:06.062717099Z" level=info msg="Start subscribing containerd event" Nov 5 23:31:06.062820 containerd[1485]: time="2025-11-05T23:31:06.062762392Z" level=info msg="Start recovering state" Nov 5 23:31:06.062862 containerd[1485]: time="2025-11-05T23:31:06.062848747Z" level=info msg="Start event monitor" Nov 5 23:31:06.062898 containerd[1485]: time="2025-11-05T23:31:06.062880559Z" level=info msg="Start snapshots syncer" Nov 5 23:31:06.062898 containerd[1485]: time="2025-11-05T23:31:06.062895315Z" level=info msg="Start cni network conf syncer for default" Nov 5 23:31:06.062933 containerd[1485]: time="2025-11-05T23:31:06.062904193Z" level=info msg="Start streaming server" Nov 5 23:31:06.063043 containerd[1485]: time="2025-11-05T23:31:06.063011673Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 5 23:31:06.063074 containerd[1485]: time="2025-11-05T23:31:06.063058488Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 5 23:31:06.063154 containerd[1485]: time="2025-11-05T23:31:06.063104686Z" level=info msg="containerd successfully booted in 0.035428s" Nov 5 23:31:06.063179 systemd[1]: Started containerd.service - containerd container runtime. Nov 5 23:31:06.509013 sshd_keygen[1474]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 5 23:31:06.529268 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 5 23:31:06.540517 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 5 23:31:06.545677 systemd[1]: issuegen.service: Deactivated successfully. Nov 5 23:31:06.545936 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 5 23:31:06.549821 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 5 23:31:06.560682 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 5 23:31:06.564599 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 5 23:31:06.566725 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 5 23:31:06.568168 systemd[1]: Reached target getty.target - Login Prompts. Nov 5 23:31:06.888290 systemd-networkd[1407]: eth0: Gained IPv6LL Nov 5 23:31:06.891346 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 5 23:31:06.893057 systemd[1]: Reached target network-online.target - Network is Online. Nov 5 23:31:06.907558 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 5 23:31:06.909732 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 23:31:06.911878 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 5 23:31:06.927249 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 5 23:31:06.928336 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 5 23:31:06.930208 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 5 23:31:06.933490 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 5 23:31:07.488880 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 23:31:07.490515 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 5 23:31:07.492521 systemd[1]: Startup finished in 534ms (kernel) + 4.417s (initrd) + 3.429s (userspace) = 8.381s. Nov 5 23:31:07.494602 (kubelet)[1564]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 5 23:31:07.848392 kubelet[1564]: E1105 23:31:07.848272 1564 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 5 23:31:07.850620 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 5 23:31:07.850770 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 5 23:31:07.851117 systemd[1]: kubelet.service: Consumed 737ms CPU time, 255.7M memory peak. Nov 5 23:31:12.628813 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 5 23:31:12.629991 systemd[1]: Started sshd@0-10.0.0.84:22-10.0.0.1:46538.service - OpenSSH per-connection server daemon (10.0.0.1:46538). Nov 5 23:31:12.690253 sshd[1577]: Accepted publickey for core from 10.0.0.1 port 46538 ssh2: RSA SHA256:xnNYYE5kPSWc/9+f0NKKtKddlIKrT81dEOZzkHePrhk Nov 5 23:31:12.691830 sshd-session[1577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 23:31:12.702949 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 5 23:31:12.718582 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 5 23:31:12.725503 systemd-logind[1466]: New session 1 of user core. Nov 5 23:31:12.731754 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 5 23:31:12.745119 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 5 23:31:12.747864 (systemd)[1581]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 5 23:31:12.750929 systemd-logind[1466]: New session c1 of user core. Nov 5 23:31:12.863708 systemd[1581]: Queued start job for default target default.target. Nov 5 23:31:12.872330 systemd[1581]: Created slice app.slice - User Application Slice. Nov 5 23:31:12.872369 systemd[1581]: Reached target paths.target - Paths. Nov 5 23:31:12.872409 systemd[1581]: Reached target timers.target - Timers. Nov 5 23:31:12.873667 systemd[1581]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 5 23:31:12.886499 systemd[1581]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 5 23:31:12.886636 systemd[1581]: Reached target sockets.target - Sockets. Nov 5 23:31:12.886682 systemd[1581]: Reached target basic.target - Basic System. Nov 5 23:31:12.886719 systemd[1581]: Reached target default.target - Main User Target. Nov 5 23:31:12.886744 systemd[1581]: Startup finished in 128ms. Nov 5 23:31:12.886830 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 5 23:31:12.890276 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 5 23:31:12.959536 systemd[1]: Started sshd@1-10.0.0.84:22-10.0.0.1:46544.service - OpenSSH per-connection server daemon (10.0.0.1:46544). Nov 5 23:31:13.016285 sshd[1592]: Accepted publickey for core from 10.0.0.1 port 46544 ssh2: RSA SHA256:xnNYYE5kPSWc/9+f0NKKtKddlIKrT81dEOZzkHePrhk Nov 5 23:31:13.017547 sshd-session[1592]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 23:31:13.023102 systemd-logind[1466]: New session 2 of user core. Nov 5 23:31:13.034650 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 5 23:31:13.090301 sshd[1594]: Connection closed by 10.0.0.1 port 46544 Nov 5 23:31:13.090802 sshd-session[1592]: pam_unix(sshd:session): session closed for user core Nov 5 23:31:13.102729 systemd[1]: sshd@1-10.0.0.84:22-10.0.0.1:46544.service: Deactivated successfully. Nov 5 23:31:13.104978 systemd[1]: session-2.scope: Deactivated successfully. Nov 5 23:31:13.110206 systemd-logind[1466]: Session 2 logged out. Waiting for processes to exit. Nov 5 23:31:13.121592 systemd[1]: Started sshd@2-10.0.0.84:22-10.0.0.1:46560.service - OpenSSH per-connection server daemon (10.0.0.1:46560). Nov 5 23:31:13.123291 systemd-logind[1466]: Removed session 2. Nov 5 23:31:13.161676 sshd[1599]: Accepted publickey for core from 10.0.0.1 port 46560 ssh2: RSA SHA256:xnNYYE5kPSWc/9+f0NKKtKddlIKrT81dEOZzkHePrhk Nov 5 23:31:13.163064 sshd-session[1599]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 23:31:13.170125 systemd-logind[1466]: New session 3 of user core. Nov 5 23:31:13.179459 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 5 23:31:13.229156 sshd[1602]: Connection closed by 10.0.0.1 port 46560 Nov 5 23:31:13.229619 sshd-session[1599]: pam_unix(sshd:session): session closed for user core Nov 5 23:31:13.245523 systemd[1]: Started sshd@3-10.0.0.84:22-10.0.0.1:46572.service - OpenSSH per-connection server daemon (10.0.0.1:46572). Nov 5 23:31:13.246665 systemd[1]: sshd@2-10.0.0.84:22-10.0.0.1:46560.service: Deactivated successfully. Nov 5 23:31:13.248087 systemd[1]: session-3.scope: Deactivated successfully. Nov 5 23:31:13.251749 systemd-logind[1466]: Session 3 logged out. Waiting for processes to exit. Nov 5 23:31:13.253657 systemd-logind[1466]: Removed session 3. Nov 5 23:31:13.287694 sshd[1605]: Accepted publickey for core from 10.0.0.1 port 46572 ssh2: RSA SHA256:xnNYYE5kPSWc/9+f0NKKtKddlIKrT81dEOZzkHePrhk Nov 5 23:31:13.289305 sshd-session[1605]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 23:31:13.293546 systemd-logind[1466]: New session 4 of user core. Nov 5 23:31:13.301417 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 5 23:31:13.354971 sshd[1610]: Connection closed by 10.0.0.1 port 46572 Nov 5 23:31:13.354843 sshd-session[1605]: pam_unix(sshd:session): session closed for user core Nov 5 23:31:13.364478 systemd[1]: sshd@3-10.0.0.84:22-10.0.0.1:46572.service: Deactivated successfully. Nov 5 23:31:13.366326 systemd[1]: session-4.scope: Deactivated successfully. Nov 5 23:31:13.368518 systemd-logind[1466]: Session 4 logged out. Waiting for processes to exit. Nov 5 23:31:13.378574 systemd[1]: Started sshd@4-10.0.0.84:22-10.0.0.1:46576.service - OpenSSH per-connection server daemon (10.0.0.1:46576). Nov 5 23:31:13.379880 systemd-logind[1466]: Removed session 4. Nov 5 23:31:13.422139 sshd[1615]: Accepted publickey for core from 10.0.0.1 port 46576 ssh2: RSA SHA256:xnNYYE5kPSWc/9+f0NKKtKddlIKrT81dEOZzkHePrhk Nov 5 23:31:13.423804 sshd-session[1615]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 5 23:31:13.428640 systemd-logind[1466]: New session 5 of user core. Nov 5 23:31:13.440427 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 5 23:31:13.497107 sudo[1619]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 5 23:31:13.497434 sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 5 23:31:13.523596 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 5 23:31:13.538930 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 5 23:31:13.539130 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 5 23:31:13.937439 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 23:31:13.937583 systemd[1]: kubelet.service: Consumed 737ms CPU time, 255.7M memory peak. Nov 5 23:31:13.949485 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 23:31:13.971186 systemd[1]: Reload requested from client PID 1663 ('systemctl') (unit session-5.scope)... Nov 5 23:31:13.971203 systemd[1]: Reloading... Nov 5 23:31:14.052309 zram_generator::config[1709]: No configuration found. Nov 5 23:31:14.291966 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 5 23:31:14.364474 systemd[1]: Reloading finished in 392 ms. Nov 5 23:31:14.402438 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 23:31:14.405116 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 23:31:14.405779 systemd[1]: kubelet.service: Deactivated successfully. Nov 5 23:31:14.405967 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 23:31:14.406001 systemd[1]: kubelet.service: Consumed 90ms CPU time, 95.1M memory peak. Nov 5 23:31:14.408483 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 5 23:31:14.507967 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 5 23:31:14.512560 (kubelet)[1753]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 5 23:31:14.552153 kubelet[1753]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 5 23:31:14.552153 kubelet[1753]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 5 23:31:14.552153 kubelet[1753]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 5 23:31:14.552153 kubelet[1753]: I1105 23:31:14.552111 1753 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 5 23:31:15.346910 kubelet[1753]: I1105 23:31:15.346850 1753 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Nov 5 23:31:15.346910 kubelet[1753]: I1105 23:31:15.346895 1753 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 5 23:31:15.347200 kubelet[1753]: I1105 23:31:15.347171 1753 server.go:954] "Client rotation is on, will bootstrap in background" Nov 5 23:31:15.368814 kubelet[1753]: I1105 23:31:15.368686 1753 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 5 23:31:15.375745 kubelet[1753]: E1105 23:31:15.375701 1753 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Nov 5 23:31:15.375745 kubelet[1753]: I1105 23:31:15.375740 1753 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Nov 5 23:31:15.379025 kubelet[1753]: I1105 23:31:15.379000 1753 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 5 23:31:15.379661 kubelet[1753]: I1105 23:31:15.379620 1753 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 5 23:31:15.379880 kubelet[1753]: I1105 23:31:15.379662 1753 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.84","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 5 23:31:15.379954 kubelet[1753]: I1105 23:31:15.379913 1753 topology_manager.go:138] "Creating topology manager with none policy" Nov 5 23:31:15.379954 kubelet[1753]: I1105 23:31:15.379923 1753 container_manager_linux.go:304] "Creating device plugin manager" Nov 5 23:31:15.380270 kubelet[1753]: I1105 23:31:15.380116 1753 state_mem.go:36] "Initialized new in-memory state store" Nov 5 23:31:15.383435 kubelet[1753]: I1105 23:31:15.383406 1753 kubelet.go:446] "Attempting to sync node with API server" Nov 5 23:31:15.383500 kubelet[1753]: I1105 23:31:15.383445 1753 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 5 23:31:15.383500 kubelet[1753]: I1105 23:31:15.383466 1753 kubelet.go:352] "Adding apiserver pod source" Nov 5 23:31:15.383500 kubelet[1753]: I1105 23:31:15.383478 1753 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 5 23:31:15.384243 kubelet[1753]: E1105 23:31:15.383959 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:15.387146 kubelet[1753]: E1105 23:31:15.386574 1753 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:15.388686 kubelet[1753]: I1105 23:31:15.388665 1753 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Nov 5 23:31:15.389363 kubelet[1753]: I1105 23:31:15.389345 1753 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Nov 5 23:31:15.389485 kubelet[1753]: W1105 23:31:15.389472 1753 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 5 23:31:15.390406 kubelet[1753]: I1105 23:31:15.390388 1753 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 5 23:31:15.390465 kubelet[1753]: I1105 23:31:15.390430 1753 server.go:1287] "Started kubelet" Nov 5 23:31:15.391290 kubelet[1753]: I1105 23:31:15.390530 1753 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Nov 5 23:31:15.391594 kubelet[1753]: I1105 23:31:15.391575 1753 server.go:479] "Adding debug handlers to kubelet server" Nov 5 23:31:15.393509 kubelet[1753]: I1105 23:31:15.393432 1753 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 5 23:31:15.393778 kubelet[1753]: I1105 23:31:15.393756 1753 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 5 23:31:15.394482 kubelet[1753]: I1105 23:31:15.394450 1753 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 5 23:31:15.396417 kubelet[1753]: I1105 23:31:15.396377 1753 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 5 23:31:15.397614 kubelet[1753]: W1105 23:31:15.397574 1753 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.84" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Nov 5 23:31:15.397614 kubelet[1753]: E1105 23:31:15.397610 1753 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.84\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 5 23:31:15.397700 kubelet[1753]: W1105 23:31:15.397662 1753 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Nov 5 23:31:15.397700 kubelet[1753]: E1105 23:31:15.397673 1753 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Nov 5 23:31:15.397834 kubelet[1753]: I1105 23:31:15.397810 1753 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 5 23:31:15.397898 kubelet[1753]: E1105 23:31:15.397876 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:15.397958 kubelet[1753]: I1105 23:31:15.397942 1753 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 5 23:31:15.398027 kubelet[1753]: I1105 23:31:15.398014 1753 reconciler.go:26] "Reconciler: start to sync state" Nov 5 23:31:15.398691 kubelet[1753]: E1105 23:31:15.398663 1753 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 5 23:31:15.399656 kubelet[1753]: E1105 23:31:15.399617 1753 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.84\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Nov 5 23:31:15.400715 kubelet[1753]: E1105 23:31:15.399733 1753 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.84.1875404099f3ff21 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.84,UID:10.0.0.84,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.84,},FirstTimestamp:2025-11-05 23:31:15.390406433 +0000 UTC m=+0.874313664,LastTimestamp:2025-11-05 23:31:15.390406433 +0000 UTC m=+0.874313664,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.84,}" Nov 5 23:31:15.401177 kubelet[1753]: I1105 23:31:15.401146 1753 factory.go:221] Registration of the containerd container factory successfully Nov 5 23:31:15.401177 kubelet[1753]: I1105 23:31:15.401169 1753 factory.go:221] Registration of the systemd container factory successfully Nov 5 23:31:15.401356 kubelet[1753]: W1105 23:31:15.401146 1753 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Nov 5 23:31:15.401356 kubelet[1753]: E1105 23:31:15.401253 1753 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" Nov 5 23:31:15.401356 kubelet[1753]: I1105 23:31:15.401278 1753 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 5 23:31:15.415005 kubelet[1753]: I1105 23:31:15.414973 1753 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 5 23:31:15.415303 kubelet[1753]: I1105 23:31:15.415166 1753 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 5 23:31:15.415303 kubelet[1753]: I1105 23:31:15.415186 1753 state_mem.go:36] "Initialized new in-memory state store" Nov 5 23:31:15.498196 kubelet[1753]: E1105 23:31:15.498143 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:15.503097 kubelet[1753]: I1105 23:31:15.502778 1753 policy_none.go:49] "None policy: Start" Nov 5 23:31:15.503097 kubelet[1753]: I1105 23:31:15.502809 1753 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 5 23:31:15.503097 kubelet[1753]: I1105 23:31:15.502823 1753 state_mem.go:35] "Initializing new in-memory state store" Nov 5 23:31:15.508888 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 5 23:31:15.526047 kubelet[1753]: I1105 23:31:15.525765 1753 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Nov 5 23:31:15.526186 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 5 23:31:15.527942 kubelet[1753]: I1105 23:31:15.527608 1753 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Nov 5 23:31:15.527942 kubelet[1753]: I1105 23:31:15.527641 1753 status_manager.go:227] "Starting to sync pod status with apiserver" Nov 5 23:31:15.527942 kubelet[1753]: I1105 23:31:15.527657 1753 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 5 23:31:15.527942 kubelet[1753]: I1105 23:31:15.527665 1753 kubelet.go:2382] "Starting kubelet main sync loop" Nov 5 23:31:15.527942 kubelet[1753]: E1105 23:31:15.527708 1753 kubelet.go:2406] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 5 23:31:15.537629 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 5 23:31:15.539366 kubelet[1753]: I1105 23:31:15.539341 1753 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Nov 5 23:31:15.539825 kubelet[1753]: I1105 23:31:15.539807 1753 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 5 23:31:15.539876 kubelet[1753]: I1105 23:31:15.539825 1753 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 5 23:31:15.540168 kubelet[1753]: I1105 23:31:15.540151 1753 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 5 23:31:15.541253 kubelet[1753]: E1105 23:31:15.541235 1753 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 5 23:31:15.541304 kubelet[1753]: E1105 23:31:15.541274 1753 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.84\" not found" Nov 5 23:31:15.604523 kubelet[1753]: E1105 23:31:15.604390 1753 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.84\" not found" node="10.0.0.84" Nov 5 23:31:15.641658 kubelet[1753]: I1105 23:31:15.641630 1753 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.84" Nov 5 23:31:15.659309 kubelet[1753]: I1105 23:31:15.659278 1753 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.84" Nov 5 23:31:15.659419 kubelet[1753]: E1105 23:31:15.659317 1753 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.84\": node \"10.0.0.84\" not found" Nov 5 23:31:15.698603 kubelet[1753]: E1105 23:31:15.698563 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:15.799622 kubelet[1753]: E1105 23:31:15.799532 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:15.900671 kubelet[1753]: E1105 23:31:15.900505 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.000769 kubelet[1753]: E1105 23:31:16.000683 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.056385 sudo[1619]: pam_unix(sudo:session): session closed for user root Nov 5 23:31:16.057768 sshd[1618]: Connection closed by 10.0.0.1 port 46576 Nov 5 23:31:16.058152 sshd-session[1615]: pam_unix(sshd:session): session closed for user core Nov 5 23:31:16.061693 systemd[1]: sshd@4-10.0.0.84:22-10.0.0.1:46576.service: Deactivated successfully. Nov 5 23:31:16.063793 systemd[1]: session-5.scope: Deactivated successfully. Nov 5 23:31:16.063984 systemd[1]: session-5.scope: Consumed 395ms CPU time, 74.6M memory peak. Nov 5 23:31:16.064909 systemd-logind[1466]: Session 5 logged out. Waiting for processes to exit. Nov 5 23:31:16.067932 systemd-logind[1466]: Removed session 5. Nov 5 23:31:16.101205 kubelet[1753]: E1105 23:31:16.101133 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.202857 kubelet[1753]: E1105 23:31:16.202748 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.303683 kubelet[1753]: E1105 23:31:16.303631 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.349143 kubelet[1753]: I1105 23:31:16.349072 1753 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 5 23:31:16.349356 kubelet[1753]: W1105 23:31:16.349329 1753 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Nov 5 23:31:16.384709 kubelet[1753]: E1105 23:31:16.384652 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:16.404104 kubelet[1753]: E1105 23:31:16.404064 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.505034 kubelet[1753]: E1105 23:31:16.504917 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.605915 kubelet[1753]: E1105 23:31:16.605860 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.706873 kubelet[1753]: E1105 23:31:16.706826 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.807470 kubelet[1753]: E1105 23:31:16.807345 1753 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.84\" not found" Nov 5 23:31:16.908695 kubelet[1753]: I1105 23:31:16.908650 1753 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 5 23:31:16.909004 containerd[1485]: time="2025-11-05T23:31:16.908956098Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 5 23:31:16.909297 kubelet[1753]: I1105 23:31:16.909136 1753 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 5 23:31:17.385491 kubelet[1753]: I1105 23:31:17.385434 1753 apiserver.go:52] "Watching apiserver" Nov 5 23:31:17.385491 kubelet[1753]: E1105 23:31:17.385489 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:17.398255 systemd[1]: Created slice kubepods-burstable-podf484f5d7_37f7_4f98_aeb6_983c5124bf51.slice - libcontainer container kubepods-burstable-podf484f5d7_37f7_4f98_aeb6_983c5124bf51.slice. Nov 5 23:31:17.398891 kubelet[1753]: I1105 23:31:17.398861 1753 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 5 23:31:17.408597 kubelet[1753]: I1105 23:31:17.408558 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dmcfj\" (UniqueName: \"kubernetes.io/projected/f484f5d7-37f7-4f98-aeb6-983c5124bf51-kube-api-access-dmcfj\") pod \"kube-flannel-ds-2w7nh\" (UID: \"f484f5d7-37f7-4f98-aeb6-983c5124bf51\") " pod="kube-flannel/kube-flannel-ds-2w7nh" Nov 5 23:31:17.408597 kubelet[1753]: I1105 23:31:17.408592 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/2560379e-38c3-403c-a5b5-67dda8acee2a-kube-proxy\") pod \"kube-proxy-p5vw7\" (UID: \"2560379e-38c3-403c-a5b5-67dda8acee2a\") " pod="kube-system/kube-proxy-p5vw7" Nov 5 23:31:17.408691 kubelet[1753]: I1105 23:31:17.408611 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/2560379e-38c3-403c-a5b5-67dda8acee2a-lib-modules\") pod \"kube-proxy-p5vw7\" (UID: \"2560379e-38c3-403c-a5b5-67dda8acee2a\") " pod="kube-system/kube-proxy-p5vw7" Nov 5 23:31:17.408691 kubelet[1753]: I1105 23:31:17.408628 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f484f5d7-37f7-4f98-aeb6-983c5124bf51-cni\") pod \"kube-flannel-ds-2w7nh\" (UID: \"f484f5d7-37f7-4f98-aeb6-983c5124bf51\") " pod="kube-flannel/kube-flannel-ds-2w7nh" Nov 5 23:31:17.408740 kubelet[1753]: I1105 23:31:17.408644 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f484f5d7-37f7-4f98-aeb6-983c5124bf51-flannel-cfg\") pod \"kube-flannel-ds-2w7nh\" (UID: \"f484f5d7-37f7-4f98-aeb6-983c5124bf51\") " pod="kube-flannel/kube-flannel-ds-2w7nh" Nov 5 23:31:17.408740 kubelet[1753]: I1105 23:31:17.408725 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f484f5d7-37f7-4f98-aeb6-983c5124bf51-xtables-lock\") pod \"kube-flannel-ds-2w7nh\" (UID: \"f484f5d7-37f7-4f98-aeb6-983c5124bf51\") " pod="kube-flannel/kube-flannel-ds-2w7nh" Nov 5 23:31:17.408780 kubelet[1753]: I1105 23:31:17.408739 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/2560379e-38c3-403c-a5b5-67dda8acee2a-xtables-lock\") pod \"kube-proxy-p5vw7\" (UID: \"2560379e-38c3-403c-a5b5-67dda8acee2a\") " pod="kube-system/kube-proxy-p5vw7" Nov 5 23:31:17.408780 kubelet[1753]: I1105 23:31:17.408755 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-f8v6q\" (UniqueName: \"kubernetes.io/projected/2560379e-38c3-403c-a5b5-67dda8acee2a-kube-api-access-f8v6q\") pod \"kube-proxy-p5vw7\" (UID: \"2560379e-38c3-403c-a5b5-67dda8acee2a\") " pod="kube-system/kube-proxy-p5vw7" Nov 5 23:31:17.408780 kubelet[1753]: I1105 23:31:17.408774 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f484f5d7-37f7-4f98-aeb6-983c5124bf51-run\") pod \"kube-flannel-ds-2w7nh\" (UID: \"f484f5d7-37f7-4f98-aeb6-983c5124bf51\") " pod="kube-flannel/kube-flannel-ds-2w7nh" Nov 5 23:31:17.408848 kubelet[1753]: I1105 23:31:17.408789 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f484f5d7-37f7-4f98-aeb6-983c5124bf51-cni-plugin\") pod \"kube-flannel-ds-2w7nh\" (UID: \"f484f5d7-37f7-4f98-aeb6-983c5124bf51\") " pod="kube-flannel/kube-flannel-ds-2w7nh" Nov 5 23:31:17.431001 systemd[1]: Created slice kubepods-besteffort-pod2560379e_38c3_403c_a5b5_67dda8acee2a.slice - libcontainer container kubepods-besteffort-pod2560379e_38c3_403c_a5b5_67dda8acee2a.slice. Nov 5 23:31:17.730540 containerd[1485]: time="2025-11-05T23:31:17.729668250Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-2w7nh,Uid:f484f5d7-37f7-4f98-aeb6-983c5124bf51,Namespace:kube-flannel,Attempt:0,}" Nov 5 23:31:17.744120 containerd[1485]: time="2025-11-05T23:31:17.744073311Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-p5vw7,Uid:2560379e-38c3-403c-a5b5-67dda8acee2a,Namespace:kube-system,Attempt:0,}" Nov 5 23:31:18.385643 kubelet[1753]: E1105 23:31:18.385591 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:18.769175 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount818518410.mount: Deactivated successfully. Nov 5 23:31:19.007587 containerd[1485]: time="2025-11-05T23:31:19.007516918Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 23:31:19.026205 containerd[1485]: time="2025-11-05T23:31:19.025006874Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 23:31:19.043295 containerd[1485]: time="2025-11-05T23:31:19.043113047Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Nov 5 23:31:19.107260 containerd[1485]: time="2025-11-05T23:31:19.107121769Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 5 23:31:19.124132 containerd[1485]: time="2025-11-05T23:31:19.124056122Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 23:31:19.194105 containerd[1485]: time="2025-11-05T23:31:19.194008833Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 5 23:31:19.195553 containerd[1485]: time="2025-11-05T23:31:19.194926224Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 1.465157353s" Nov 5 23:31:19.214248 containerd[1485]: time="2025-11-05T23:31:19.214104455Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 1.469963169s" Nov 5 23:31:19.387784 kubelet[1753]: E1105 23:31:19.387629 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:19.667357 containerd[1485]: time="2025-11-05T23:31:19.667127152Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 5 23:31:19.667357 containerd[1485]: time="2025-11-05T23:31:19.667199581Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 5 23:31:19.667357 containerd[1485]: time="2025-11-05T23:31:19.667218231Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:19.667357 containerd[1485]: time="2025-11-05T23:31:19.667314577Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:19.670441 containerd[1485]: time="2025-11-05T23:31:19.670324957Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 5 23:31:19.670608 containerd[1485]: time="2025-11-05T23:31:19.670413625Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 5 23:31:19.670608 containerd[1485]: time="2025-11-05T23:31:19.670550647Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:19.671870 containerd[1485]: time="2025-11-05T23:31:19.671488256Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:19.741391 systemd[1]: Started cri-containerd-38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd.scope - libcontainer container 38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd. Nov 5 23:31:19.742720 systemd[1]: Started cri-containerd-899d95b196811751b92af0ef70ea6c9c4b3eef5271b52f98f1fbd54607b2eb1b.scope - libcontainer container 899d95b196811751b92af0ef70ea6c9c4b3eef5271b52f98f1fbd54607b2eb1b. Nov 5 23:31:19.767455 containerd[1485]: time="2025-11-05T23:31:19.767178680Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-p5vw7,Uid:2560379e-38c3-403c-a5b5-67dda8acee2a,Namespace:kube-system,Attempt:0,} returns sandbox id \"899d95b196811751b92af0ef70ea6c9c4b3eef5271b52f98f1fbd54607b2eb1b\"" Nov 5 23:31:19.770381 containerd[1485]: time="2025-11-05T23:31:19.770344692Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\"" Nov 5 23:31:19.773138 containerd[1485]: time="2025-11-05T23:31:19.773102090Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-2w7nh,Uid:f484f5d7-37f7-4f98-aeb6-983c5124bf51,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd\"" Nov 5 23:31:20.387788 kubelet[1753]: E1105 23:31:20.387747 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:21.388150 kubelet[1753]: E1105 23:31:21.388089 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:21.787321 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2793507615.mount: Deactivated successfully. Nov 5 23:31:22.357320 containerd[1485]: time="2025-11-05T23:31:22.357259580Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:22.388758 kubelet[1753]: E1105 23:31:22.388705 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:22.414638 containerd[1485]: time="2025-11-05T23:31:22.414571802Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.9: active requests=0, bytes read=27417819" Nov 5 23:31:22.434440 containerd[1485]: time="2025-11-05T23:31:22.434398458Z" level=info msg="ImageCreate event name:\"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:22.453331 containerd[1485]: time="2025-11-05T23:31:22.453296986Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:22.454025 containerd[1485]: time="2025-11-05T23:31:22.453972496Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.9\" with image id \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\", repo tag \"registry.k8s.io/kube-proxy:v1.32.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\", size \"27416836\" in 2.683582533s" Nov 5 23:31:22.454025 containerd[1485]: time="2025-11-05T23:31:22.454009817Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\" returns image reference \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\"" Nov 5 23:31:22.455653 containerd[1485]: time="2025-11-05T23:31:22.455609641Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Nov 5 23:31:22.456647 containerd[1485]: time="2025-11-05T23:31:22.456618672Z" level=info msg="CreateContainer within sandbox \"899d95b196811751b92af0ef70ea6c9c4b3eef5271b52f98f1fbd54607b2eb1b\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 5 23:31:22.719625 containerd[1485]: time="2025-11-05T23:31:22.719441972Z" level=info msg="CreateContainer within sandbox \"899d95b196811751b92af0ef70ea6c9c4b3eef5271b52f98f1fbd54607b2eb1b\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"46e6743e98eb967441423ee33f4bbdae013cbf0855cb0b13889434369adeaa40\"" Nov 5 23:31:22.721113 containerd[1485]: time="2025-11-05T23:31:22.720844477Z" level=info msg="StartContainer for \"46e6743e98eb967441423ee33f4bbdae013cbf0855cb0b13889434369adeaa40\"" Nov 5 23:31:22.745387 systemd[1]: Started cri-containerd-46e6743e98eb967441423ee33f4bbdae013cbf0855cb0b13889434369adeaa40.scope - libcontainer container 46e6743e98eb967441423ee33f4bbdae013cbf0855cb0b13889434369adeaa40. Nov 5 23:31:22.796430 containerd[1485]: time="2025-11-05T23:31:22.796375585Z" level=info msg="StartContainer for \"46e6743e98eb967441423ee33f4bbdae013cbf0855cb0b13889434369adeaa40\" returns successfully" Nov 5 23:31:23.389354 kubelet[1753]: E1105 23:31:23.389319 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:23.552024 kubelet[1753]: I1105 23:31:23.551753 1753 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-p5vw7" podStartSLOduration=5.866611347 podStartE2EDuration="8.551734185s" podCreationTimestamp="2025-11-05 23:31:15 +0000 UTC" firstStartedPulling="2025-11-05 23:31:19.769848254 +0000 UTC m=+5.253755484" lastFinishedPulling="2025-11-05 23:31:22.454971052 +0000 UTC m=+7.938878322" observedRunningTime="2025-11-05 23:31:23.551525995 +0000 UTC m=+9.035433265" watchObservedRunningTime="2025-11-05 23:31:23.551734185 +0000 UTC m=+9.035641416" Nov 5 23:31:23.707051 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4186662852.mount: Deactivated successfully. Nov 5 23:31:23.733650 containerd[1485]: time="2025-11-05T23:31:23.733608228Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:23.734847 containerd[1485]: time="2025-11-05T23:31:23.734776142Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Nov 5 23:31:23.735826 containerd[1485]: time="2025-11-05T23:31:23.735799164Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:23.737821 containerd[1485]: time="2025-11-05T23:31:23.737785479Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:23.739406 containerd[1485]: time="2025-11-05T23:31:23.739283408Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.283636691s" Nov 5 23:31:23.739406 containerd[1485]: time="2025-11-05T23:31:23.739314737Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Nov 5 23:31:23.741092 containerd[1485]: time="2025-11-05T23:31:23.741064782Z" level=info msg="CreateContainer within sandbox \"38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 5 23:31:23.751394 containerd[1485]: time="2025-11-05T23:31:23.751360069Z" level=info msg="CreateContainer within sandbox \"38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa\"" Nov 5 23:31:23.751924 containerd[1485]: time="2025-11-05T23:31:23.751872683Z" level=info msg="StartContainer for \"5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa\"" Nov 5 23:31:23.774386 systemd[1]: Started cri-containerd-5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa.scope - libcontainer container 5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa. Nov 5 23:31:23.796118 systemd[1]: cri-containerd-5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa.scope: Deactivated successfully. Nov 5 23:31:23.798820 containerd[1485]: time="2025-11-05T23:31:23.798789222Z" level=info msg="StartContainer for \"5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa\" returns successfully" Nov 5 23:31:23.873719 containerd[1485]: time="2025-11-05T23:31:23.873546342Z" level=info msg="shim disconnected" id=5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa namespace=k8s.io Nov 5 23:31:23.873719 containerd[1485]: time="2025-11-05T23:31:23.873597567Z" level=warning msg="cleaning up after shim disconnected" id=5c38df2b24748525654f9d866e42a84cfaf9968012b1091f92db3e93e7ce86fa namespace=k8s.io Nov 5 23:31:23.873719 containerd[1485]: time="2025-11-05T23:31:23.873606433Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 5 23:31:24.390113 kubelet[1753]: E1105 23:31:24.390043 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:24.547202 containerd[1485]: time="2025-11-05T23:31:24.546886068Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Nov 5 23:31:25.390801 kubelet[1753]: E1105 23:31:25.390721 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:25.726601 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3169958762.mount: Deactivated successfully. Nov 5 23:31:26.277407 containerd[1485]: time="2025-11-05T23:31:26.277354941Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:26.278430 containerd[1485]: time="2025-11-05T23:31:26.278155665Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Nov 5 23:31:26.279231 containerd[1485]: time="2025-11-05T23:31:26.279185864Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:26.282935 containerd[1485]: time="2025-11-05T23:31:26.282877727Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:26.284349 containerd[1485]: time="2025-11-05T23:31:26.284314661Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.737386375s" Nov 5 23:31:26.284448 containerd[1485]: time="2025-11-05T23:31:26.284353575Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Nov 5 23:31:26.286376 containerd[1485]: time="2025-11-05T23:31:26.286311139Z" level=info msg="CreateContainer within sandbox \"38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 5 23:31:26.299098 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2746445109.mount: Deactivated successfully. Nov 5 23:31:26.303462 containerd[1485]: time="2025-11-05T23:31:26.303414915Z" level=info msg="CreateContainer within sandbox \"38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0\"" Nov 5 23:31:26.303982 containerd[1485]: time="2025-11-05T23:31:26.303918794Z" level=info msg="StartContainer for \"adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0\"" Nov 5 23:31:26.328429 systemd[1]: Started cri-containerd-adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0.scope - libcontainer container adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0. Nov 5 23:31:26.349525 systemd[1]: cri-containerd-adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0.scope: Deactivated successfully. Nov 5 23:31:26.351494 containerd[1485]: time="2025-11-05T23:31:26.351456785Z" level=info msg="StartContainer for \"adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0\" returns successfully" Nov 5 23:31:26.391932 kubelet[1753]: E1105 23:31:26.391859 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:26.401653 kubelet[1753]: I1105 23:31:26.401576 1753 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 5 23:31:26.469596 containerd[1485]: time="2025-11-05T23:31:26.469518093Z" level=info msg="shim disconnected" id=adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0 namespace=k8s.io Nov 5 23:31:26.469912 containerd[1485]: time="2025-11-05T23:31:26.469746327Z" level=warning msg="cleaning up after shim disconnected" id=adbb38736e80efe4f193f91120b5082c3a080f7f0c93abd16077ff6cfaae4fc0 namespace=k8s.io Nov 5 23:31:26.469912 containerd[1485]: time="2025-11-05T23:31:26.469761196Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 5 23:31:26.555660 containerd[1485]: time="2025-11-05T23:31:26.555162695Z" level=info msg="CreateContainer within sandbox \"38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 5 23:31:26.756788 containerd[1485]: time="2025-11-05T23:31:26.756719877Z" level=info msg="CreateContainer within sandbox \"38af1aca4f06b52014ba3d6bb633745c09d05a35be64de28008d8ec45c5b64cd\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"a30e49db4c88aacb64807825fb9df3da0b319d52c77d1e27fcc461e6ffc3e5e9\"" Nov 5 23:31:26.757360 containerd[1485]: time="2025-11-05T23:31:26.757327913Z" level=info msg="StartContainer for \"a30e49db4c88aacb64807825fb9df3da0b319d52c77d1e27fcc461e6ffc3e5e9\"" Nov 5 23:31:26.784479 systemd[1]: Started cri-containerd-a30e49db4c88aacb64807825fb9df3da0b319d52c77d1e27fcc461e6ffc3e5e9.scope - libcontainer container a30e49db4c88aacb64807825fb9df3da0b319d52c77d1e27fcc461e6ffc3e5e9. Nov 5 23:31:26.837914 containerd[1485]: time="2025-11-05T23:31:26.837787812Z" level=info msg="StartContainer for \"a30e49db4c88aacb64807825fb9df3da0b319d52c77d1e27fcc461e6ffc3e5e9\" returns successfully" Nov 5 23:31:27.154335 systemd[1]: Created slice kubepods-besteffort-pode27638eb_f369_4c6b_aa4d_f7bb3ef31d9a.slice - libcontainer container kubepods-besteffort-pode27638eb_f369_4c6b_aa4d_f7bb3ef31d9a.slice. Nov 5 23:31:27.174235 kubelet[1753]: I1105 23:31:27.174166 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-b6r48\" (UniqueName: \"kubernetes.io/projected/e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a-kube-api-access-b6r48\") pod \"nginx-deployment-7fcdb87857-dmdhw\" (UID: \"e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a\") " pod="default/nginx-deployment-7fcdb87857-dmdhw" Nov 5 23:31:27.392296 kubelet[1753]: E1105 23:31:27.392255 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:27.457122 containerd[1485]: time="2025-11-05T23:31:27.457011004Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-dmdhw,Uid:e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a,Namespace:default,Attempt:0,}" Nov 5 23:31:27.483658 containerd[1485]: time="2025-11-05T23:31:27.483043786Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-dmdhw,Uid:e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"75a1f8d1c2be2c20e8843a8d8581a7c2bef07032fb601f394372c33ca78a486a\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 5 23:31:27.483822 kubelet[1753]: E1105 23:31:27.483268 1753 log.go:32] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"75a1f8d1c2be2c20e8843a8d8581a7c2bef07032fb601f394372c33ca78a486a\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 5 23:31:27.483822 kubelet[1753]: E1105 23:31:27.483345 1753 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"75a1f8d1c2be2c20e8843a8d8581a7c2bef07032fb601f394372c33ca78a486a\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-7fcdb87857-dmdhw" Nov 5 23:31:27.483822 kubelet[1753]: E1105 23:31:27.483367 1753 kuberuntime_manager.go:1237] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"75a1f8d1c2be2c20e8843a8d8581a7c2bef07032fb601f394372c33ca78a486a\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-7fcdb87857-dmdhw" Nov 5 23:31:27.483822 kubelet[1753]: E1105 23:31:27.483412 1753 pod_workers.go:1301] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-7fcdb87857-dmdhw_default(e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-7fcdb87857-dmdhw_default(e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"75a1f8d1c2be2c20e8843a8d8581a7c2bef07032fb601f394372c33ca78a486a\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-7fcdb87857-dmdhw" podUID="e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a" Nov 5 23:31:27.568091 kubelet[1753]: I1105 23:31:27.568044 1753 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-2w7nh" podStartSLOduration=6.056882489 podStartE2EDuration="12.568028197s" podCreationTimestamp="2025-11-05 23:31:15 +0000 UTC" firstStartedPulling="2025-11-05 23:31:19.773913449 +0000 UTC m=+5.257820639" lastFinishedPulling="2025-11-05 23:31:26.285059117 +0000 UTC m=+11.768966347" observedRunningTime="2025-11-05 23:31:27.56777165 +0000 UTC m=+13.051678880" watchObservedRunningTime="2025-11-05 23:31:27.568028197 +0000 UTC m=+13.051935427" Nov 5 23:31:27.862796 systemd-networkd[1407]: flannel.1: Link UP Nov 5 23:31:27.862803 systemd-networkd[1407]: flannel.1: Gained carrier Nov 5 23:31:28.393181 kubelet[1753]: E1105 23:31:28.393129 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:29.159349 systemd-networkd[1407]: flannel.1: Gained IPv6LL Nov 5 23:31:29.393681 kubelet[1753]: E1105 23:31:29.393629 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:30.394037 kubelet[1753]: E1105 23:31:30.393989 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:31.394267 kubelet[1753]: E1105 23:31:31.394186 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:32.395078 kubelet[1753]: E1105 23:31:32.395031 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:33.396259 kubelet[1753]: E1105 23:31:33.396187 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:34.397403 kubelet[1753]: E1105 23:31:34.397349 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:35.383589 kubelet[1753]: E1105 23:31:35.383536 1753 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:35.398421 kubelet[1753]: E1105 23:31:35.398370 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:36.399389 kubelet[1753]: E1105 23:31:36.399333 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:37.400343 kubelet[1753]: E1105 23:31:37.400293 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:38.401116 kubelet[1753]: E1105 23:31:38.401058 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:39.401328 kubelet[1753]: E1105 23:31:39.401266 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:40.401919 kubelet[1753]: E1105 23:31:40.401865 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:41.403235 kubelet[1753]: E1105 23:31:41.403145 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:42.403531 kubelet[1753]: E1105 23:31:42.403428 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:42.528969 containerd[1485]: time="2025-11-05T23:31:42.528914264Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-dmdhw,Uid:e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a,Namespace:default,Attempt:0,}" Nov 5 23:31:42.546435 systemd-networkd[1407]: cni0: Link UP Nov 5 23:31:42.546441 systemd-networkd[1407]: cni0: Gained carrier Nov 5 23:31:42.548496 systemd-networkd[1407]: cni0: Lost carrier Nov 5 23:31:42.553428 systemd-networkd[1407]: vethb536d349: Link UP Nov 5 23:31:42.556317 kernel: cni0: port 1(vethb536d349) entered blocking state Nov 5 23:31:42.556398 kernel: cni0: port 1(vethb536d349) entered disabled state Nov 5 23:31:42.556414 kernel: vethb536d349: entered allmulticast mode Nov 5 23:31:42.556745 kernel: vethb536d349: entered promiscuous mode Nov 5 23:31:42.558800 kernel: cni0: port 1(vethb536d349) entered blocking state Nov 5 23:31:42.559566 kernel: cni0: port 1(vethb536d349) entered forwarding state Nov 5 23:31:42.559606 kernel: cni0: port 1(vethb536d349) entered disabled state Nov 5 23:31:42.568244 kernel: cni0: port 1(vethb536d349) entered blocking state Nov 5 23:31:42.568307 kernel: cni0: port 1(vethb536d349) entered forwarding state Nov 5 23:31:42.568361 systemd-networkd[1407]: vethb536d349: Gained carrier Nov 5 23:31:42.568600 systemd-networkd[1407]: cni0: Gained carrier Nov 5 23:31:42.570758 containerd[1485]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Nov 5 23:31:42.570758 containerd[1485]: delegateAdd: netconf sent to delegate plugin: Nov 5 23:31:42.588823 containerd[1485]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-05T23:31:42.588705922Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 5 23:31:42.588823 containerd[1485]: time="2025-11-05T23:31:42.588765981Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 5 23:31:42.588823 containerd[1485]: time="2025-11-05T23:31:42.588781985Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:42.589071 containerd[1485]: time="2025-11-05T23:31:42.588876974Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:42.611421 systemd[1]: Started cri-containerd-4228520501a96ea34a15ac57f82d1940bc16b4f5a1905caba12ce2eb407afbd7.scope - libcontainer container 4228520501a96ea34a15ac57f82d1940bc16b4f5a1905caba12ce2eb407afbd7. Nov 5 23:31:42.621702 systemd-resolved[1324]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 5 23:31:42.636481 containerd[1485]: time="2025-11-05T23:31:42.636441587Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-dmdhw,Uid:e27638eb-f369-4c6b-aa4d-f7bb3ef31d9a,Namespace:default,Attempt:0,} returns sandbox id \"4228520501a96ea34a15ac57f82d1940bc16b4f5a1905caba12ce2eb407afbd7\"" Nov 5 23:31:42.639664 containerd[1485]: time="2025-11-05T23:31:42.639633469Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 23:31:43.404565 kubelet[1753]: E1105 23:31:43.404513 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:43.942386 systemd-networkd[1407]: cni0: Gained IPv6LL Nov 5 23:31:43.942657 systemd-networkd[1407]: vethb536d349: Gained IPv6LL Nov 5 23:31:44.404936 kubelet[1753]: E1105 23:31:44.404901 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:44.682013 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1908394803.mount: Deactivated successfully. Nov 5 23:31:45.374050 containerd[1485]: time="2025-11-05T23:31:45.374004291Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:45.374749 containerd[1485]: time="2025-11-05T23:31:45.374712152Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Nov 5 23:31:45.376303 containerd[1485]: time="2025-11-05T23:31:45.376274751Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:45.379028 containerd[1485]: time="2025-11-05T23:31:45.379002289Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:45.380570 containerd[1485]: time="2025-11-05T23:31:45.380546324Z" 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.740779255s" Nov 5 23:31:45.380651 containerd[1485]: time="2025-11-05T23:31:45.380572850Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 23:31:45.382450 containerd[1485]: time="2025-11-05T23:31:45.382424644Z" level=info msg="CreateContainer within sandbox \"4228520501a96ea34a15ac57f82d1940bc16b4f5a1905caba12ce2eb407afbd7\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 5 23:31:45.391136 containerd[1485]: time="2025-11-05T23:31:45.391101422Z" level=info msg="CreateContainer within sandbox \"4228520501a96ea34a15ac57f82d1940bc16b4f5a1905caba12ce2eb407afbd7\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"b13f844aaa722bde7478188e0a083a978424665a48b53a197497e345822ac565\"" Nov 5 23:31:45.394234 containerd[1485]: time="2025-11-05T23:31:45.391896226Z" level=info msg="StartContainer for \"b13f844aaa722bde7478188e0a083a978424665a48b53a197497e345822ac565\"" Nov 5 23:31:45.406087 kubelet[1753]: E1105 23:31:45.406058 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:45.425624 systemd[1]: Started cri-containerd-b13f844aaa722bde7478188e0a083a978424665a48b53a197497e345822ac565.scope - libcontainer container b13f844aaa722bde7478188e0a083a978424665a48b53a197497e345822ac565. Nov 5 23:31:45.447979 containerd[1485]: time="2025-11-05T23:31:45.447934395Z" level=info msg="StartContainer for \"b13f844aaa722bde7478188e0a083a978424665a48b53a197497e345822ac565\" returns successfully" Nov 5 23:31:46.406822 kubelet[1753]: E1105 23:31:46.406772 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:47.407974 kubelet[1753]: E1105 23:31:47.407922 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:48.408300 kubelet[1753]: E1105 23:31:48.408260 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:49.173265 kubelet[1753]: I1105 23:31:49.172745 1753 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-dmdhw" podStartSLOduration=19.42888717 podStartE2EDuration="22.172726354s" podCreationTimestamp="2025-11-05 23:31:27 +0000 UTC" firstStartedPulling="2025-11-05 23:31:42.637415361 +0000 UTC m=+28.121322591" lastFinishedPulling="2025-11-05 23:31:45.381254545 +0000 UTC m=+30.865161775" observedRunningTime="2025-11-05 23:31:45.595247143 +0000 UTC m=+31.079154373" watchObservedRunningTime="2025-11-05 23:31:49.172726354 +0000 UTC m=+34.656633584" Nov 5 23:31:49.179427 systemd[1]: Created slice kubepods-besteffort-pod6f476243_f5e2_4a09_a615_0931cd6d1a33.slice - libcontainer container kubepods-besteffort-pod6f476243_f5e2_4a09_a615_0931cd6d1a33.slice. Nov 5 23:31:49.209949 kubelet[1753]: I1105 23:31:49.209846 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-d7jfr\" (UniqueName: \"kubernetes.io/projected/6f476243-f5e2-4a09-a615-0931cd6d1a33-kube-api-access-d7jfr\") pod \"nfs-server-provisioner-0\" (UID: \"6f476243-f5e2-4a09-a615-0931cd6d1a33\") " pod="default/nfs-server-provisioner-0" Nov 5 23:31:49.209949 kubelet[1753]: I1105 23:31:49.209888 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/6f476243-f5e2-4a09-a615-0931cd6d1a33-data\") pod \"nfs-server-provisioner-0\" (UID: \"6f476243-f5e2-4a09-a615-0931cd6d1a33\") " pod="default/nfs-server-provisioner-0" Nov 5 23:31:49.409182 kubelet[1753]: E1105 23:31:49.409141 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:49.482196 containerd[1485]: time="2025-11-05T23:31:49.482087978Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:6f476243-f5e2-4a09-a615-0931cd6d1a33,Namespace:default,Attempt:0,}" Nov 5 23:31:49.506604 systemd-networkd[1407]: vetha860c6d2: Link UP Nov 5 23:31:49.508890 kernel: cni0: port 2(vetha860c6d2) entered blocking state Nov 5 23:31:49.508952 kernel: cni0: port 2(vetha860c6d2) entered disabled state Nov 5 23:31:49.508968 kernel: vetha860c6d2: entered allmulticast mode Nov 5 23:31:49.508983 kernel: vetha860c6d2: entered promiscuous mode Nov 5 23:31:49.514221 kernel: cni0: port 2(vetha860c6d2) entered blocking state Nov 5 23:31:49.514277 kernel: cni0: port 2(vetha860c6d2) entered forwarding state Nov 5 23:31:49.515407 systemd-networkd[1407]: vetha860c6d2: Gained carrier Nov 5 23:31:49.517557 containerd[1485]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016938), "name":"cbr0", "type":"bridge"} Nov 5 23:31:49.517557 containerd[1485]: delegateAdd: netconf sent to delegate plugin: Nov 5 23:31:49.534403 containerd[1485]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-05T23:31:49.534338158Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 5 23:31:49.534571 containerd[1485]: time="2025-11-05T23:31:49.534383968Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 5 23:31:49.534571 containerd[1485]: time="2025-11-05T23:31:49.534394730Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:49.534571 containerd[1485]: time="2025-11-05T23:31:49.534457663Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:31:49.558382 systemd[1]: Started cri-containerd-1d1680f868dc3d32e7710456b70e0c7434387d244a05d954b5b82dd9ff12ad0d.scope - libcontainer container 1d1680f868dc3d32e7710456b70e0c7434387d244a05d954b5b82dd9ff12ad0d. Nov 5 23:31:49.568393 systemd-resolved[1324]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 5 23:31:49.584263 containerd[1485]: time="2025-11-05T23:31:49.584225409Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:6f476243-f5e2-4a09-a615-0931cd6d1a33,Namespace:default,Attempt:0,} returns sandbox id \"1d1680f868dc3d32e7710456b70e0c7434387d244a05d954b5b82dd9ff12ad0d\"" Nov 5 23:31:49.585795 containerd[1485]: time="2025-11-05T23:31:49.585767848Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 5 23:31:50.409336 kubelet[1753]: E1105 23:31:50.409283 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:51.113696 update_engine[1468]: I20251105 23:31:51.113623 1468 update_attempter.cc:509] Updating boot flags... Nov 5 23:31:51.135346 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2654) Nov 5 23:31:51.176243 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (2654) Nov 5 23:31:51.409505 kubelet[1753]: E1105 23:31:51.409379 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:51.430433 systemd-networkd[1407]: vetha860c6d2: Gained IPv6LL Nov 5 23:31:52.220072 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1274002444.mount: Deactivated successfully. Nov 5 23:31:52.409939 kubelet[1753]: E1105 23:31:52.409881 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:53.410569 kubelet[1753]: E1105 23:31:53.410528 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:53.564511 containerd[1485]: time="2025-11-05T23:31:53.564447817Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:53.565301 containerd[1485]: time="2025-11-05T23:31:53.565252314Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Nov 5 23:31:53.566038 containerd[1485]: time="2025-11-05T23:31:53.565992999Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:53.569530 containerd[1485]: time="2025-11-05T23:31:53.569460867Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:31:53.570457 containerd[1485]: time="2025-11-05T23:31:53.570432272Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.984623656s" Nov 5 23:31:53.570641 containerd[1485]: time="2025-11-05T23:31:53.570537010Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Nov 5 23:31:53.573135 containerd[1485]: time="2025-11-05T23:31:53.573103565Z" level=info msg="CreateContainer within sandbox \"1d1680f868dc3d32e7710456b70e0c7434387d244a05d954b5b82dd9ff12ad0d\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 5 23:31:53.587339 containerd[1485]: time="2025-11-05T23:31:53.587283409Z" level=info msg="CreateContainer within sandbox \"1d1680f868dc3d32e7710456b70e0c7434387d244a05d954b5b82dd9ff12ad0d\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"c434a06b165cf6e40e22ada5598d5abaa70097123e37df4b06d1a4265ff5db4d\"" Nov 5 23:31:53.587914 containerd[1485]: time="2025-11-05T23:31:53.587881110Z" level=info msg="StartContainer for \"c434a06b165cf6e40e22ada5598d5abaa70097123e37df4b06d1a4265ff5db4d\"" Nov 5 23:31:53.614401 systemd[1]: Started cri-containerd-c434a06b165cf6e40e22ada5598d5abaa70097123e37df4b06d1a4265ff5db4d.scope - libcontainer container c434a06b165cf6e40e22ada5598d5abaa70097123e37df4b06d1a4265ff5db4d. Nov 5 23:31:53.636736 containerd[1485]: time="2025-11-05T23:31:53.636689864Z" level=info msg="StartContainer for \"c434a06b165cf6e40e22ada5598d5abaa70097123e37df4b06d1a4265ff5db4d\" returns successfully" Nov 5 23:31:54.411578 kubelet[1753]: E1105 23:31:54.411528 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:54.615998 kubelet[1753]: I1105 23:31:54.615933 1753 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.629675004 podStartE2EDuration="5.615917152s" podCreationTimestamp="2025-11-05 23:31:49 +0000 UTC" firstStartedPulling="2025-11-05 23:31:49.585333118 +0000 UTC m=+35.069240348" lastFinishedPulling="2025-11-05 23:31:53.571575266 +0000 UTC m=+39.055482496" observedRunningTime="2025-11-05 23:31:54.615499204 +0000 UTC m=+40.099406434" watchObservedRunningTime="2025-11-05 23:31:54.615917152 +0000 UTC m=+40.099824382" Nov 5 23:31:55.383617 kubelet[1753]: E1105 23:31:55.383563 1753 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:55.411996 kubelet[1753]: E1105 23:31:55.411951 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:56.412958 kubelet[1753]: E1105 23:31:56.412882 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:57.414070 kubelet[1753]: E1105 23:31:57.414018 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:58.414799 kubelet[1753]: E1105 23:31:58.414738 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:31:59.415422 kubelet[1753]: E1105 23:31:59.415359 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:00.416285 kubelet[1753]: E1105 23:32:00.416177 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:01.416896 kubelet[1753]: E1105 23:32:01.416849 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:02.417571 kubelet[1753]: E1105 23:32:02.417519 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:03.418631 kubelet[1753]: E1105 23:32:03.418574 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:03.868236 systemd[1]: Created slice kubepods-besteffort-pod1e073c79_1818_466f_87d7_5b8a57fef602.slice - libcontainer container kubepods-besteffort-pod1e073c79_1818_466f_87d7_5b8a57fef602.slice. Nov 5 23:32:03.895035 kubelet[1753]: I1105 23:32:03.894971 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-1b7a33b4-887e-4119-9a58-a1a1cddb7cec\" (UniqueName: \"kubernetes.io/nfs/1e073c79-1818-466f-87d7-5b8a57fef602-pvc-1b7a33b4-887e-4119-9a58-a1a1cddb7cec\") pod \"test-pod-1\" (UID: \"1e073c79-1818-466f-87d7-5b8a57fef602\") " pod="default/test-pod-1" Nov 5 23:32:03.895035 kubelet[1753]: I1105 23:32:03.895023 1753 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lmhbw\" (UniqueName: \"kubernetes.io/projected/1e073c79-1818-466f-87d7-5b8a57fef602-kube-api-access-lmhbw\") pod \"test-pod-1\" (UID: \"1e073c79-1818-466f-87d7-5b8a57fef602\") " pod="default/test-pod-1" Nov 5 23:32:04.012262 kernel: FS-Cache: Loaded Nov 5 23:32:04.036665 kernel: RPC: Registered named UNIX socket transport module. Nov 5 23:32:04.036765 kernel: RPC: Registered udp transport module. Nov 5 23:32:04.036783 kernel: RPC: Registered tcp transport module. Nov 5 23:32:04.036810 kernel: RPC: Registered tcp-with-tls transport module. Nov 5 23:32:04.037321 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 5 23:32:04.195702 kernel: NFS: Registering the id_resolver key type Nov 5 23:32:04.195822 kernel: Key type id_resolver registered Nov 5 23:32:04.195860 kernel: Key type id_legacy registered Nov 5 23:32:04.215164 nfsidmap[2898]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 5 23:32:04.216922 nfsidmap[2899]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 5 23:32:04.418894 kubelet[1753]: E1105 23:32:04.418850 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:04.471761 containerd[1485]: time="2025-11-05T23:32:04.471644238Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1e073c79-1818-466f-87d7-5b8a57fef602,Namespace:default,Attempt:0,}" Nov 5 23:32:04.489746 systemd-networkd[1407]: veth2ee249e9: Link UP Nov 5 23:32:04.490703 kernel: cni0: port 3(veth2ee249e9) entered blocking state Nov 5 23:32:04.490750 kernel: cni0: port 3(veth2ee249e9) entered disabled state Nov 5 23:32:04.490766 kernel: veth2ee249e9: entered allmulticast mode Nov 5 23:32:04.492245 kernel: veth2ee249e9: entered promiscuous mode Nov 5 23:32:04.498451 kernel: cni0: port 3(veth2ee249e9) entered blocking state Nov 5 23:32:04.498615 kernel: cni0: port 3(veth2ee249e9) entered forwarding state Nov 5 23:32:04.498758 systemd-networkd[1407]: veth2ee249e9: Gained carrier Nov 5 23:32:04.500266 containerd[1485]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400001a938), "name":"cbr0", "type":"bridge"} Nov 5 23:32:04.500266 containerd[1485]: delegateAdd: netconf sent to delegate plugin: Nov 5 23:32:04.521315 containerd[1485]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-05T23:32:04.521067532Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 5 23:32:04.521315 containerd[1485]: time="2025-11-05T23:32:04.521136739Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 5 23:32:04.521315 containerd[1485]: time="2025-11-05T23:32:04.521151981Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:32:04.521315 containerd[1485]: time="2025-11-05T23:32:04.521264113Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 5 23:32:04.540387 systemd[1]: Started cri-containerd-c4d7259207d401b3781413299143fa183b6510bf3dfb69c58a21c3297cb8dd40.scope - libcontainer container c4d7259207d401b3781413299143fa183b6510bf3dfb69c58a21c3297cb8dd40. Nov 5 23:32:04.550671 systemd-resolved[1324]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 5 23:32:04.566807 containerd[1485]: time="2025-11-05T23:32:04.566772157Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1e073c79-1818-466f-87d7-5b8a57fef602,Namespace:default,Attempt:0,} returns sandbox id \"c4d7259207d401b3781413299143fa183b6510bf3dfb69c58a21c3297cb8dd40\"" Nov 5 23:32:04.567897 containerd[1485]: time="2025-11-05T23:32:04.567859511Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 5 23:32:04.813084 containerd[1485]: time="2025-11-05T23:32:04.813032740Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 5 23:32:04.813758 containerd[1485]: time="2025-11-05T23:32:04.813565715Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 5 23:32:04.820625 containerd[1485]: time="2025-11-05T23:32:04.820569729Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 252.676174ms" Nov 5 23:32:04.820625 containerd[1485]: time="2025-11-05T23:32:04.820616534Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Nov 5 23:32:04.822489 containerd[1485]: time="2025-11-05T23:32:04.822439965Z" level=info msg="CreateContainer within sandbox \"c4d7259207d401b3781413299143fa183b6510bf3dfb69c58a21c3297cb8dd40\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 5 23:32:04.833538 containerd[1485]: time="2025-11-05T23:32:04.833453718Z" level=info msg="CreateContainer within sandbox \"c4d7259207d401b3781413299143fa183b6510bf3dfb69c58a21c3297cb8dd40\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"69b65a20b90393121c8c05660f902bba03eb27b1143c93371fc1910ebe3682fe\"" Nov 5 23:32:04.834207 containerd[1485]: time="2025-11-05T23:32:04.833917326Z" level=info msg="StartContainer for \"69b65a20b90393121c8c05660f902bba03eb27b1143c93371fc1910ebe3682fe\"" Nov 5 23:32:04.861401 systemd[1]: Started cri-containerd-69b65a20b90393121c8c05660f902bba03eb27b1143c93371fc1910ebe3682fe.scope - libcontainer container 69b65a20b90393121c8c05660f902bba03eb27b1143c93371fc1910ebe3682fe. Nov 5 23:32:04.883329 containerd[1485]: time="2025-11-05T23:32:04.883281654Z" level=info msg="StartContainer for \"69b65a20b90393121c8c05660f902bba03eb27b1143c93371fc1910ebe3682fe\" returns successfully" Nov 5 23:32:05.419568 kubelet[1753]: E1105 23:32:05.419515 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:06.406365 systemd-networkd[1407]: veth2ee249e9: Gained IPv6LL Nov 5 23:32:06.419989 kubelet[1753]: E1105 23:32:06.419900 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:07.420599 kubelet[1753]: E1105 23:32:07.420547 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:08.420720 kubelet[1753]: E1105 23:32:08.420651 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:09.421737 kubelet[1753]: E1105 23:32:09.421657 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:10.422718 kubelet[1753]: E1105 23:32:10.422535 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:11.423330 kubelet[1753]: E1105 23:32:11.423269 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 5 23:32:12.424116 kubelet[1753]: E1105 23:32:12.424061 1753 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"