Oct 29 23:30:12.354771 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 23:30:12.354798 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Wed Oct 29 22:08:13 -00 2025 Oct 29 23:30:12.354807 kernel: KASLR enabled Oct 29 23:30:12.354813 kernel: efi: EFI v2.7 by EDK II Oct 29 23:30:12.354818 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Oct 29 23:30:12.354824 kernel: random: crng init done Oct 29 23:30:12.354831 kernel: secureboot: Secure boot disabled Oct 29 23:30:12.354837 kernel: ACPI: Early table checksum verification disabled Oct 29 23:30:12.354844 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 23:30:12.354851 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 23:30:12.354903 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354910 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354917 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354923 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354933 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354940 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354946 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354953 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354959 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 23:30:12.354966 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 23:30:12.354972 kernel: ACPI: Use ACPI SPCR as default console: No Oct 29 23:30:12.354979 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 23:30:12.354987 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Oct 29 23:30:12.354993 kernel: Zone ranges: Oct 29 23:30:12.354999 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 23:30:12.355006 kernel: DMA32 empty Oct 29 23:30:12.355012 kernel: Normal empty Oct 29 23:30:12.355018 kernel: Device empty Oct 29 23:30:12.355024 kernel: Movable zone start for each node Oct 29 23:30:12.355031 kernel: Early memory node ranges Oct 29 23:30:12.355037 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Oct 29 23:30:12.355044 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Oct 29 23:30:12.355050 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Oct 29 23:30:12.355057 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 23:30:12.355065 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 23:30:12.355071 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 23:30:12.355077 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 23:30:12.355084 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 23:30:12.355090 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 23:30:12.355097 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 23:30:12.355107 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 23:30:12.355114 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 23:30:12.355121 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 23:30:12.355128 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 23:30:12.355135 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 29 23:30:12.355142 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Oct 29 23:30:12.355149 kernel: psci: probing for conduit method from ACPI. Oct 29 23:30:12.355156 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 23:30:12.355164 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 23:30:12.355171 kernel: psci: Trusted OS migration not required Oct 29 23:30:12.355178 kernel: psci: SMC Calling Convention v1.1 Oct 29 23:30:12.355249 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 29 23:30:12.355258 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 29 23:30:12.355265 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 29 23:30:12.355272 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 23:30:12.355279 kernel: Detected PIPT I-cache on CPU0 Oct 29 23:30:12.355286 kernel: CPU features: detected: GIC system register CPU interface Oct 29 23:30:12.355293 kernel: CPU features: detected: Spectre-v4 Oct 29 23:30:12.355301 kernel: CPU features: detected: Spectre-BHB Oct 29 23:30:12.355311 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 23:30:12.355318 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 23:30:12.355325 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 23:30:12.355332 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 29 23:30:12.355339 kernel: alternatives: applying boot alternatives Oct 29 23:30:12.355347 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=c3f6e690ee2ade37dd6082d1ad3b53d2d12b3a76b4644e8ca271364e3a8c31ac Oct 29 23:30:12.355354 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 23:30:12.355361 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 23:30:12.355368 kernel: Fallback order for Node 0: 0 Oct 29 23:30:12.355375 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 29 23:30:12.355383 kernel: Policy zone: DMA Oct 29 23:30:12.355390 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 23:30:12.355397 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 29 23:30:12.355404 kernel: software IO TLB: area num 4. Oct 29 23:30:12.355411 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 29 23:30:12.355419 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Oct 29 23:30:12.355426 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 23:30:12.355433 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 23:30:12.355442 kernel: rcu: RCU event tracing is enabled. Oct 29 23:30:12.355449 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 23:30:12.355456 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 23:30:12.355464 kernel: Tracing variant of Tasks RCU enabled. Oct 29 23:30:12.355472 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 23:30:12.355479 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 23:30:12.355486 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 29 23:30:12.355493 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 29 23:30:12.355500 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 23:30:12.355507 kernel: GICv3: 256 SPIs implemented Oct 29 23:30:12.355514 kernel: GICv3: 0 Extended SPIs implemented Oct 29 23:30:12.355521 kernel: Root IRQ handler: gic_handle_irq Oct 29 23:30:12.355528 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 29 23:30:12.355535 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 29 23:30:12.355544 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 23:30:12.355551 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 23:30:12.355558 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 29 23:30:12.355566 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 29 23:30:12.355573 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 29 23:30:12.355581 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 29 23:30:12.355588 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 29 23:30:12.355595 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 23:30:12.355602 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 23:30:12.355609 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 23:30:12.355617 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 23:30:12.355626 kernel: arm-pv: using stolen time PV Oct 29 23:30:12.355634 kernel: Console: colour dummy device 80x25 Oct 29 23:30:12.355642 kernel: ACPI: Core revision 20240827 Oct 29 23:30:12.355649 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 23:30:12.355657 kernel: pid_max: default: 32768 minimum: 301 Oct 29 23:30:12.355665 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 29 23:30:12.355672 kernel: landlock: Up and running. Oct 29 23:30:12.355680 kernel: SELinux: Initializing. Oct 29 23:30:12.355689 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 23:30:12.355697 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 23:30:12.355705 kernel: rcu: Hierarchical SRCU implementation. Oct 29 23:30:12.355712 kernel: rcu: Max phase no-delay instances is 400. Oct 29 23:30:12.355720 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 29 23:30:12.355727 kernel: Remapping and enabling EFI services. Oct 29 23:30:12.355735 kernel: smp: Bringing up secondary CPUs ... Oct 29 23:30:12.355743 kernel: Detected PIPT I-cache on CPU1 Oct 29 23:30:12.355755 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 23:30:12.355764 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 29 23:30:12.355772 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 23:30:12.355779 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 23:30:12.355787 kernel: Detected PIPT I-cache on CPU2 Oct 29 23:30:12.355796 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 23:30:12.355806 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 29 23:30:12.355814 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 23:30:12.355822 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 23:30:12.355830 kernel: Detected PIPT I-cache on CPU3 Oct 29 23:30:12.355838 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 23:30:12.355847 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 29 23:30:12.355855 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 23:30:12.355864 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 23:30:12.355872 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 23:30:12.355880 kernel: SMP: Total of 4 processors activated. Oct 29 23:30:12.355888 kernel: CPU: All CPU(s) started at EL1 Oct 29 23:30:12.355896 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 23:30:12.355905 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 23:30:12.355912 kernel: CPU features: detected: Common not Private translations Oct 29 23:30:12.355921 kernel: CPU features: detected: CRC32 instructions Oct 29 23:30:12.355930 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 29 23:30:12.355938 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 29 23:30:12.355946 kernel: CPU features: detected: LSE atomic instructions Oct 29 23:30:12.355955 kernel: CPU features: detected: Privileged Access Never Oct 29 23:30:12.355963 kernel: CPU features: detected: RAS Extension Support Oct 29 23:30:12.355971 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 23:30:12.355979 kernel: alternatives: applying system-wide alternatives Oct 29 23:30:12.355988 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 29 23:30:12.355996 kernel: Memory: 2450400K/2572288K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 99552K reserved, 16384K cma-reserved) Oct 29 23:30:12.356004 kernel: devtmpfs: initialized Oct 29 23:30:12.356012 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 23:30:12.356021 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 23:30:12.356029 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 29 23:30:12.356037 kernel: 0 pages in range for non-PLT usage Oct 29 23:30:12.356047 kernel: 515056 pages in range for PLT usage Oct 29 23:30:12.356055 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 23:30:12.356063 kernel: SMBIOS 3.0.0 present. Oct 29 23:30:12.356071 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 23:30:12.356079 kernel: DMI: Memory slots populated: 1/1 Oct 29 23:30:12.356086 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 29 23:30:12.356094 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 23:30:12.356103 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 23:30:12.356111 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 23:30:12.356118 kernel: audit: initializing netlink subsys (disabled) Oct 29 23:30:12.356126 kernel: audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 Oct 29 23:30:12.356134 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 23:30:12.356141 kernel: cpuidle: using governor menu Oct 29 23:30:12.356149 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 23:30:12.356158 kernel: ASID allocator initialised with 32768 entries Oct 29 23:30:12.356165 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 23:30:12.356173 kernel: Serial: AMBA PL011 UART driver Oct 29 23:30:12.356181 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 23:30:12.356196 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 29 23:30:12.356204 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 23:30:12.356211 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 29 23:30:12.356219 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 23:30:12.356235 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 29 23:30:12.356243 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 23:30:12.356250 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 29 23:30:12.356258 kernel: ACPI: Added _OSI(Module Device) Oct 29 23:30:12.356266 kernel: ACPI: Added _OSI(Processor Device) Oct 29 23:30:12.356273 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 23:30:12.356281 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 23:30:12.356290 kernel: ACPI: Interpreter enabled Oct 29 23:30:12.356298 kernel: ACPI: Using GIC for interrupt routing Oct 29 23:30:12.356305 kernel: ACPI: MCFG table detected, 1 entries Oct 29 23:30:12.356313 kernel: ACPI: CPU0 has been hot-added Oct 29 23:30:12.356320 kernel: ACPI: CPU1 has been hot-added Oct 29 23:30:12.356328 kernel: ACPI: CPU2 has been hot-added Oct 29 23:30:12.356335 kernel: ACPI: CPU3 has been hot-added Oct 29 23:30:12.356344 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 23:30:12.356352 kernel: printk: legacy console [ttyAMA0] enabled Oct 29 23:30:12.356359 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 23:30:12.356526 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 23:30:12.356614 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 23:30:12.356696 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 23:30:12.356780 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 23:30:12.356859 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 23:30:12.356869 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 23:30:12.356877 kernel: PCI host bridge to bus 0000:00 Oct 29 23:30:12.356962 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 23:30:12.357037 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 23:30:12.357112 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 23:30:12.357205 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 23:30:12.357326 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 29 23:30:12.357420 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 29 23:30:12.357507 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 29 23:30:12.357591 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 29 23:30:12.357671 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 23:30:12.357751 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 29 23:30:12.357830 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 29 23:30:12.357909 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 29 23:30:12.357983 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 23:30:12.358059 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 23:30:12.358134 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 23:30:12.358144 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 29 23:30:12.358153 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 29 23:30:12.358161 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 29 23:30:12.358169 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 29 23:30:12.358177 kernel: iommu: Default domain type: Translated Oct 29 23:30:12.358198 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 29 23:30:12.358206 kernel: efivars: Registered efivars operations Oct 29 23:30:12.358214 kernel: vgaarb: loaded Oct 29 23:30:12.358231 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 23:30:12.358240 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 23:30:12.358247 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 23:30:12.358256 kernel: pnp: PnP ACPI init Oct 29 23:30:12.358383 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 23:30:12.358394 kernel: pnp: PnP ACPI: found 1 devices Oct 29 23:30:12.358402 kernel: NET: Registered PF_INET protocol family Oct 29 23:30:12.358422 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 23:30:12.358430 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 23:30:12.358438 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 29 23:30:12.358447 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 23:30:12.358456 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 29 23:30:12.358464 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 23:30:12.358472 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 23:30:12.358480 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 23:30:12.358488 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 29 23:30:12.358496 kernel: PCI: CLS 0 bytes, default 64 Oct 29 23:30:12.358503 kernel: kvm [1]: HYP mode not available Oct 29 23:30:12.358512 kernel: Initialise system trusted keyrings Oct 29 23:30:12.358520 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 23:30:12.358528 kernel: Key type asymmetric registered Oct 29 23:30:12.358536 kernel: Asymmetric key parser 'x509' registered Oct 29 23:30:12.358543 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 29 23:30:12.358551 kernel: io scheduler mq-deadline registered Oct 29 23:30:12.358559 kernel: io scheduler kyber registered Oct 29 23:30:12.358568 kernel: io scheduler bfq registered Oct 29 23:30:12.358577 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 23:30:12.358585 kernel: ACPI: button: Power Button [PWRB] Oct 29 23:30:12.358594 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 29 23:30:12.358680 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 23:30:12.358690 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 23:30:12.358698 kernel: thunder_xcv, ver 1.0 Oct 29 23:30:12.358707 kernel: thunder_bgx, ver 1.0 Oct 29 23:30:12.358715 kernel: nicpf, ver 1.0 Oct 29 23:30:12.358723 kernel: nicvf, ver 1.0 Oct 29 23:30:12.358814 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 23:30:12.358892 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T23:30:11 UTC (1761780611) Oct 29 23:30:12.358902 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 23:30:12.358912 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 29 23:30:12.358920 kernel: watchdog: NMI not fully supported Oct 29 23:30:12.358928 kernel: watchdog: Hard watchdog permanently disabled Oct 29 23:30:12.358936 kernel: NET: Registered PF_INET6 protocol family Oct 29 23:30:12.358944 kernel: Segment Routing with IPv6 Oct 29 23:30:12.358951 kernel: In-situ OAM (IOAM) with IPv6 Oct 29 23:30:12.358959 kernel: NET: Registered PF_PACKET protocol family Oct 29 23:30:12.358966 kernel: Key type dns_resolver registered Oct 29 23:30:12.358976 kernel: registered taskstats version 1 Oct 29 23:30:12.358984 kernel: Loading compiled-in X.509 certificates Oct 29 23:30:12.358991 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: c6256e7d9c20dfbb4deda09bbb20ce7eab6ed949' Oct 29 23:30:12.358999 kernel: Demotion targets for Node 0: null Oct 29 23:30:12.359007 kernel: Key type .fscrypt registered Oct 29 23:30:12.359014 kernel: Key type fscrypt-provisioning registered Oct 29 23:30:12.359022 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 23:30:12.359031 kernel: ima: Allocated hash algorithm: sha1 Oct 29 23:30:12.359039 kernel: ima: No architecture policies found Oct 29 23:30:12.359047 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 29 23:30:12.359055 kernel: clk: Disabling unused clocks Oct 29 23:30:12.359062 kernel: PM: genpd: Disabling unused power domains Oct 29 23:30:12.359070 kernel: Freeing unused kernel memory: 12992K Oct 29 23:30:12.359078 kernel: Run /init as init process Oct 29 23:30:12.359087 kernel: with arguments: Oct 29 23:30:12.359095 kernel: /init Oct 29 23:30:12.359102 kernel: with environment: Oct 29 23:30:12.359110 kernel: HOME=/ Oct 29 23:30:12.359117 kernel: TERM=linux Oct 29 23:30:12.359240 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 29 23:30:12.359328 kernel: virtio_blk virtio1: [vda] 27000832 512-byte logical blocks (13.8 GB/12.9 GiB) Oct 29 23:30:12.359343 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 29 23:30:12.359351 kernel: GPT:16515071 != 27000831 Oct 29 23:30:12.359358 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 29 23:30:12.359366 kernel: GPT:16515071 != 27000831 Oct 29 23:30:12.359373 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 29 23:30:12.359381 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 23:30:12.359390 kernel: SCSI subsystem initialized Oct 29 23:30:12.359398 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 29 23:30:12.359406 kernel: device-mapper: uevent: version 1.0.3 Oct 29 23:30:12.359413 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 29 23:30:12.359422 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 29 23:30:12.359429 kernel: raid6: neonx8 gen() 15751 MB/s Oct 29 23:30:12.359437 kernel: raid6: neonx4 gen() 15791 MB/s Oct 29 23:30:12.359446 kernel: raid6: neonx2 gen() 13037 MB/s Oct 29 23:30:12.359454 kernel: raid6: neonx1 gen() 10218 MB/s Oct 29 23:30:12.359461 kernel: raid6: int64x8 gen() 6758 MB/s Oct 29 23:30:12.359469 kernel: raid6: int64x4 gen() 7248 MB/s Oct 29 23:30:12.359476 kernel: raid6: int64x2 gen() 6008 MB/s Oct 29 23:30:12.359488 kernel: raid6: int64x1 gen() 4981 MB/s Oct 29 23:30:12.359496 kernel: raid6: using algorithm neonx4 gen() 15791 MB/s Oct 29 23:30:12.359505 kernel: raid6: .... xor() 12180 MB/s, rmw enabled Oct 29 23:30:12.359513 kernel: raid6: using neon recovery algorithm Oct 29 23:30:12.359521 kernel: xor: measuring software checksum speed Oct 29 23:30:12.359529 kernel: 8regs : 20302 MB/sec Oct 29 23:30:12.359537 kernel: 32regs : 21664 MB/sec Oct 29 23:30:12.359544 kernel: arm64_neon : 25584 MB/sec Oct 29 23:30:12.359553 kernel: xor: using function: arm64_neon (25584 MB/sec) Oct 29 23:30:12.359560 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 29 23:30:12.359570 kernel: BTRFS: device fsid f84bda9c-c65c-4b2e-9db1-1debc07ad11f devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (207) Oct 29 23:30:12.359578 kernel: BTRFS info (device dm-0): first mount of filesystem f84bda9c-c65c-4b2e-9db1-1debc07ad11f Oct 29 23:30:12.359586 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 29 23:30:12.359595 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 29 23:30:12.359603 kernel: BTRFS info (device dm-0): enabling free space tree Oct 29 23:30:12.359611 kernel: loop: module loaded Oct 29 23:30:12.359619 kernel: loop0: detected capacity change from 0 to 91464 Oct 29 23:30:12.359628 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 29 23:30:12.359638 systemd[1]: Successfully made /usr/ read-only. Oct 29 23:30:12.359649 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 29 23:30:12.359658 systemd[1]: Detected virtualization kvm. Oct 29 23:30:12.359667 systemd[1]: Detected architecture arm64. Oct 29 23:30:12.359676 systemd[1]: Running in initrd. Oct 29 23:30:12.359685 systemd[1]: No hostname configured, using default hostname. Oct 29 23:30:12.359693 systemd[1]: Hostname set to . Oct 29 23:30:12.359702 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 29 23:30:12.359710 systemd[1]: Queued start job for default target initrd.target. Oct 29 23:30:12.359718 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 29 23:30:12.359726 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 23:30:12.359736 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 23:30:12.359745 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 29 23:30:12.359754 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 29 23:30:12.359762 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 29 23:30:12.359771 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 29 23:30:12.359781 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 23:30:12.359789 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 29 23:30:12.359797 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 29 23:30:12.359806 systemd[1]: Reached target paths.target - Path Units. Oct 29 23:30:12.359814 systemd[1]: Reached target slices.target - Slice Units. Oct 29 23:30:12.359822 systemd[1]: Reached target swap.target - Swaps. Oct 29 23:30:12.359830 systemd[1]: Reached target timers.target - Timer Units. Oct 29 23:30:12.359840 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 29 23:30:12.359848 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 29 23:30:12.359857 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 29 23:30:12.359865 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 29 23:30:12.359881 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 29 23:30:12.359892 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 29 23:30:12.359900 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 23:30:12.359909 systemd[1]: Reached target sockets.target - Socket Units. Oct 29 23:30:12.359917 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 29 23:30:12.359926 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 29 23:30:12.359934 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 29 23:30:12.359943 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 29 23:30:12.359953 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 29 23:30:12.359962 systemd[1]: Starting systemd-fsck-usr.service... Oct 29 23:30:12.359970 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 29 23:30:12.359979 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 29 23:30:12.359987 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 23:30:12.359998 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 29 23:30:12.360007 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 23:30:12.360015 systemd[1]: Finished systemd-fsck-usr.service. Oct 29 23:30:12.360024 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 29 23:30:12.360033 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 29 23:30:12.360060 systemd-journald[348]: Collecting audit messages is disabled. Oct 29 23:30:12.360080 kernel: Bridge firewalling registered Oct 29 23:30:12.360089 systemd-journald[348]: Journal started Oct 29 23:30:12.360109 systemd-journald[348]: Runtime Journal (/run/log/journal/30dc52d94fc4429aa2a6d43b1014e0aa) is 6M, max 48.5M, 42.4M free. Oct 29 23:30:12.354740 systemd-modules-load[349]: Inserted module 'br_netfilter' Oct 29 23:30:12.364411 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 29 23:30:12.367848 systemd[1]: Started systemd-journald.service - Journal Service. Oct 29 23:30:12.368507 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 23:30:12.373323 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 29 23:30:12.376980 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 29 23:30:12.378974 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 29 23:30:12.381980 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 29 23:30:12.388259 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 29 23:30:12.396375 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 23:30:12.399247 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 29 23:30:12.399808 systemd-tmpfiles[371]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 29 23:30:12.403812 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 29 23:30:12.406492 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 29 23:30:12.416450 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 23:30:12.421340 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 29 23:30:12.451797 systemd-resolved[387]: Positive Trust Anchors: Oct 29 23:30:12.451815 systemd-resolved[387]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 23:30:12.454392 dracut-cmdline[393]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=c3f6e690ee2ade37dd6082d1ad3b53d2d12b3a76b4644e8ca271364e3a8c31ac Oct 29 23:30:12.451819 systemd-resolved[387]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 29 23:30:12.451850 systemd-resolved[387]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 29 23:30:12.476661 systemd-resolved[387]: Defaulting to hostname 'linux'. Oct 29 23:30:12.478089 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 29 23:30:12.479365 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 29 23:30:12.533234 kernel: Loading iSCSI transport class v2.0-870. Oct 29 23:30:12.541214 kernel: iscsi: registered transport (tcp) Oct 29 23:30:12.555212 kernel: iscsi: registered transport (qla4xxx) Oct 29 23:30:12.555281 kernel: QLogic iSCSI HBA Driver Oct 29 23:30:12.575858 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 29 23:30:12.598338 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 23:30:12.600544 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 29 23:30:12.647993 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 29 23:30:12.650655 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 29 23:30:12.652401 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 29 23:30:12.698512 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 29 23:30:12.701596 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 23:30:12.735501 systemd-udevd[633]: Using default interface naming scheme 'v257'. Oct 29 23:30:12.743530 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 23:30:12.748131 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 29 23:30:12.773303 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 29 23:30:12.777920 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 29 23:30:12.780427 dracut-pre-trigger[701]: rd.md=0: removing MD RAID activation Oct 29 23:30:12.807296 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 29 23:30:12.809974 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 29 23:30:12.820330 systemd-networkd[740]: lo: Link UP Oct 29 23:30:12.820337 systemd-networkd[740]: lo: Gained carrier Oct 29 23:30:12.820776 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 29 23:30:12.822327 systemd[1]: Reached target network.target - Network. Oct 29 23:30:12.866364 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 23:30:12.870130 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 29 23:30:12.912471 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 29 23:30:12.914458 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 29 23:30:12.929841 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 29 23:30:12.937981 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 29 23:30:12.945150 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 29 23:30:12.947893 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 29 23:30:12.950123 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 23:30:12.951596 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 29 23:30:12.959726 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 29 23:30:12.965373 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 29 23:30:12.969330 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 23:30:12.969451 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 23:30:12.971671 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 23:30:12.971854 systemd-networkd[740]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 23:30:12.971858 systemd-networkd[740]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 23:30:12.972941 systemd-networkd[740]: eth0: Link UP Oct 29 23:30:12.973740 systemd-networkd[740]: eth0: Gained carrier Oct 29 23:30:12.973751 systemd-networkd[740]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 23:30:12.978823 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 23:30:12.990272 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 29 23:30:12.994827 disk-uuid[810]: Primary Header is updated. Oct 29 23:30:12.994827 disk-uuid[810]: Secondary Entries is updated. Oct 29 23:30:12.994827 disk-uuid[810]: Secondary Header is updated. Oct 29 23:30:12.997094 systemd-networkd[740]: eth0: DHCPv4 address 10.0.0.44/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 29 23:30:13.011792 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 23:30:14.024871 disk-uuid[818]: Warning: The kernel is still using the old partition table. Oct 29 23:30:14.024871 disk-uuid[818]: The new table will be used at the next reboot or after you Oct 29 23:30:14.024871 disk-uuid[818]: run partprobe(8) or kpartx(8) Oct 29 23:30:14.024871 disk-uuid[818]: The operation has completed successfully. Oct 29 23:30:14.029590 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 23:30:14.029692 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 29 23:30:14.032180 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 29 23:30:14.061316 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (833) Oct 29 23:30:14.061357 kernel: BTRFS info (device vda6): first mount of filesystem 69a058e1-a2ca-4b98-8b6f-1187a84af986 Oct 29 23:30:14.063404 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 29 23:30:14.066487 kernel: BTRFS info (device vda6): turning on async discard Oct 29 23:30:14.066519 kernel: BTRFS info (device vda6): enabling free space tree Oct 29 23:30:14.072214 kernel: BTRFS info (device vda6): last unmount of filesystem 69a058e1-a2ca-4b98-8b6f-1187a84af986 Oct 29 23:30:14.072515 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 29 23:30:14.074452 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 29 23:30:14.177867 ignition[852]: Ignition 2.22.0 Oct 29 23:30:14.178771 ignition[852]: Stage: fetch-offline Oct 29 23:30:14.178829 ignition[852]: no configs at "/usr/lib/ignition/base.d" Oct 29 23:30:14.178840 ignition[852]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 23:30:14.178920 ignition[852]: parsed url from cmdline: "" Oct 29 23:30:14.178923 ignition[852]: no config URL provided Oct 29 23:30:14.178927 ignition[852]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 23:30:14.178935 ignition[852]: no config at "/usr/lib/ignition/user.ign" Oct 29 23:30:14.178972 ignition[852]: op(1): [started] loading QEMU firmware config module Oct 29 23:30:14.178976 ignition[852]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 29 23:30:14.185274 ignition[852]: op(1): [finished] loading QEMU firmware config module Oct 29 23:30:14.192672 ignition[852]: parsing config with SHA512: 3ec5d066c769eed18e9d7bebd227e4d7617f707a3af65a00c6ad5d42b01f22a68047a48de024a22e6d80052a246c82b3d7a400c7b4b53ec2c2d7ed34d5496f3f Oct 29 23:30:14.196230 unknown[852]: fetched base config from "system" Oct 29 23:30:14.196242 unknown[852]: fetched user config from "qemu" Oct 29 23:30:14.196499 ignition[852]: fetch-offline: fetch-offline passed Oct 29 23:30:14.198863 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 29 23:30:14.196556 ignition[852]: Ignition finished successfully Oct 29 23:30:14.200301 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 29 23:30:14.202372 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 29 23:30:14.244864 ignition[866]: Ignition 2.22.0 Oct 29 23:30:14.244884 ignition[866]: Stage: kargs Oct 29 23:30:14.245014 ignition[866]: no configs at "/usr/lib/ignition/base.d" Oct 29 23:30:14.245022 ignition[866]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 23:30:14.245598 ignition[866]: kargs: kargs passed Oct 29 23:30:14.245637 ignition[866]: Ignition finished successfully Oct 29 23:30:14.250855 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 29 23:30:14.253000 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 29 23:30:14.285232 ignition[874]: Ignition 2.22.0 Oct 29 23:30:14.285247 ignition[874]: Stage: disks Oct 29 23:30:14.285383 ignition[874]: no configs at "/usr/lib/ignition/base.d" Oct 29 23:30:14.285392 ignition[874]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 23:30:14.287626 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 29 23:30:14.285957 ignition[874]: disks: disks passed Oct 29 23:30:14.288896 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 29 23:30:14.285995 ignition[874]: Ignition finished successfully Oct 29 23:30:14.290761 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 29 23:30:14.292805 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 29 23:30:14.294308 systemd[1]: Reached target sysinit.target - System Initialization. Oct 29 23:30:14.296256 systemd[1]: Reached target basic.target - Basic System. Oct 29 23:30:14.298817 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 29 23:30:14.333814 systemd-fsck[884]: ROOT: clean, 15/456736 files, 38230/456704 blocks Oct 29 23:30:14.337761 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 29 23:30:14.340055 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 29 23:30:14.399092 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 29 23:30:14.400721 kernel: EXT4-fs (vda9): mounted filesystem 1648bfe3-fe28-4898-89ac-a64f076d042f r/w with ordered data mode. Quota mode: none. Oct 29 23:30:14.400477 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 29 23:30:14.403743 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 29 23:30:14.406073 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 29 23:30:14.407236 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 29 23:30:14.407283 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 29 23:30:14.407309 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 29 23:30:14.419587 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 29 23:30:14.422330 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 29 23:30:14.428119 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (892) Oct 29 23:30:14.428142 kernel: BTRFS info (device vda6): first mount of filesystem 69a058e1-a2ca-4b98-8b6f-1187a84af986 Oct 29 23:30:14.428153 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 29 23:30:14.429878 kernel: BTRFS info (device vda6): turning on async discard Oct 29 23:30:14.429913 kernel: BTRFS info (device vda6): enabling free space tree Oct 29 23:30:14.430888 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 29 23:30:14.459501 initrd-setup-root[918]: cut: /sysroot/etc/passwd: No such file or directory Oct 29 23:30:14.463232 initrd-setup-root[925]: cut: /sysroot/etc/group: No such file or directory Oct 29 23:30:14.467292 initrd-setup-root[932]: cut: /sysroot/etc/shadow: No such file or directory Oct 29 23:30:14.470808 initrd-setup-root[939]: cut: /sysroot/etc/gshadow: No such file or directory Oct 29 23:30:14.540664 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 29 23:30:14.543300 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 29 23:30:14.545113 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 29 23:30:14.565326 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 29 23:30:14.567098 kernel: BTRFS info (device vda6): last unmount of filesystem 69a058e1-a2ca-4b98-8b6f-1187a84af986 Oct 29 23:30:14.580381 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 29 23:30:14.599175 ignition[1008]: INFO : Ignition 2.22.0 Oct 29 23:30:14.599175 ignition[1008]: INFO : Stage: mount Oct 29 23:30:14.600835 ignition[1008]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 23:30:14.600835 ignition[1008]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 23:30:14.600835 ignition[1008]: INFO : mount: mount passed Oct 29 23:30:14.600835 ignition[1008]: INFO : Ignition finished successfully Oct 29 23:30:14.601985 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 29 23:30:14.605434 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 29 23:30:14.719360 systemd-networkd[740]: eth0: Gained IPv6LL Oct 29 23:30:15.400653 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 29 23:30:15.420253 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1020) Oct 29 23:30:15.420295 kernel: BTRFS info (device vda6): first mount of filesystem 69a058e1-a2ca-4b98-8b6f-1187a84af986 Oct 29 23:30:15.420317 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 29 23:30:15.423886 kernel: BTRFS info (device vda6): turning on async discard Oct 29 23:30:15.423908 kernel: BTRFS info (device vda6): enabling free space tree Oct 29 23:30:15.425365 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 29 23:30:15.453528 ignition[1037]: INFO : Ignition 2.22.0 Oct 29 23:30:15.453528 ignition[1037]: INFO : Stage: files Oct 29 23:30:15.455383 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 23:30:15.455383 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 23:30:15.455383 ignition[1037]: DEBUG : files: compiled without relabeling support, skipping Oct 29 23:30:15.455383 ignition[1037]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 29 23:30:15.455383 ignition[1037]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 29 23:30:15.462455 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 23:30:15.458274 unknown[1037]: wrote ssh authorized keys file for user: core Oct 29 23:30:15.477661 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 23:30:15.477661 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 23:30:15.477661 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Oct 29 23:30:15.854235 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 29 23:30:16.179272 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Oct 29 23:30:16.179272 ignition[1037]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 29 23:30:16.183694 ignition[1037]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 29 23:30:16.183694 ignition[1037]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 29 23:30:16.183694 ignition[1037]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 29 23:30:16.183694 ignition[1037]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 29 23:30:16.198093 ignition[1037]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 29 23:30:16.201815 ignition[1037]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 29 23:30:16.203467 ignition[1037]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 29 23:30:16.203467 ignition[1037]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 29 23:30:16.203467 ignition[1037]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 29 23:30:16.203467 ignition[1037]: INFO : files: files passed Oct 29 23:30:16.203467 ignition[1037]: INFO : Ignition finished successfully Oct 29 23:30:16.204350 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 29 23:30:16.207286 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 29 23:30:16.209769 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 29 23:30:16.222982 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 23:30:16.223121 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 29 23:30:16.226768 initrd-setup-root-after-ignition[1068]: grep: /sysroot/oem/oem-release: No such file or directory Oct 29 23:30:16.228556 initrd-setup-root-after-ignition[1070]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 29 23:30:16.228556 initrd-setup-root-after-ignition[1070]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 29 23:30:16.231800 initrd-setup-root-after-ignition[1074]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 29 23:30:16.231256 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 29 23:30:16.233469 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 29 23:30:16.236358 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 29 23:30:16.280627 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 23:30:16.281938 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 29 23:30:16.283488 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 29 23:30:16.285283 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 29 23:30:16.287097 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 29 23:30:16.288074 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 29 23:30:16.304603 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 29 23:30:16.309327 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 29 23:30:16.330506 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 29 23:30:16.330720 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 29 23:30:16.333077 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 23:30:16.335406 systemd[1]: Stopped target timers.target - Timer Units. Oct 29 23:30:16.337472 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 23:30:16.337610 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 29 23:30:16.340358 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 29 23:30:16.341593 systemd[1]: Stopped target basic.target - Basic System. Oct 29 23:30:16.343652 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 29 23:30:16.345724 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 29 23:30:16.347573 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 29 23:30:16.349560 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 29 23:30:16.351768 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 29 23:30:16.353784 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 29 23:30:16.355948 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 29 23:30:16.357873 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 29 23:30:16.359988 systemd[1]: Stopped target swap.target - Swaps. Oct 29 23:30:16.361718 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 23:30:16.361863 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 29 23:30:16.364338 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 29 23:30:16.366369 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 23:30:16.368410 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 29 23:30:16.369298 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 23:30:16.371581 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 23:30:16.371710 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 29 23:30:16.374602 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 29 23:30:16.374713 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 29 23:30:16.376874 systemd[1]: Stopped target paths.target - Path Units. Oct 29 23:30:16.378517 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 23:30:16.380230 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 23:30:16.381721 systemd[1]: Stopped target slices.target - Slice Units. Oct 29 23:30:16.383316 systemd[1]: Stopped target sockets.target - Socket Units. Oct 29 23:30:16.385173 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 23:30:16.385285 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 29 23:30:16.387513 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 23:30:16.387597 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 29 23:30:16.389267 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 29 23:30:16.389393 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 29 23:30:16.391286 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 23:30:16.391403 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 29 23:30:16.393876 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 29 23:30:16.396230 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 29 23:30:16.397256 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 29 23:30:16.397388 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 23:30:16.399570 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 23:30:16.399675 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 23:30:16.401774 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 23:30:16.401881 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 29 23:30:16.407466 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 23:30:16.413229 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 29 23:30:16.420439 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 29 23:30:16.426463 ignition[1094]: INFO : Ignition 2.22.0 Oct 29 23:30:16.426463 ignition[1094]: INFO : Stage: umount Oct 29 23:30:16.429259 ignition[1094]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 29 23:30:16.429259 ignition[1094]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 29 23:30:16.429259 ignition[1094]: INFO : umount: umount passed Oct 29 23:30:16.429259 ignition[1094]: INFO : Ignition finished successfully Oct 29 23:30:16.429014 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 29 23:30:16.429272 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 29 23:30:16.431501 systemd[1]: Stopped target network.target - Network. Oct 29 23:30:16.433409 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 23:30:16.433475 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 29 23:30:16.435070 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 29 23:30:16.435117 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 29 23:30:16.436862 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 23:30:16.436910 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 29 23:30:16.438742 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 29 23:30:16.438785 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 29 23:30:16.440893 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 29 23:30:16.442639 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 29 23:30:16.447716 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 23:30:16.447834 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 29 23:30:16.451003 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 23:30:16.451081 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 29 23:30:16.452592 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 23:30:16.452675 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 29 23:30:16.455779 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 23:30:16.455893 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 29 23:30:16.459498 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 29 23:30:16.461476 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 23:30:16.461511 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 29 23:30:16.464094 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 29 23:30:16.465059 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 29 23:30:16.465125 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 29 23:30:16.467377 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 23:30:16.467421 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 29 23:30:16.469296 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 29 23:30:16.469351 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 29 23:30:16.471274 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 23:30:16.490528 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 23:30:16.496342 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 23:30:16.497892 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 23:30:16.497927 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 29 23:30:16.500059 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 23:30:16.500093 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 23:30:16.502192 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 23:30:16.502253 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 29 23:30:16.505180 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 23:30:16.505338 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 29 23:30:16.508064 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 23:30:16.508112 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 29 23:30:16.511280 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 29 23:30:16.512442 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 29 23:30:16.512501 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 23:30:16.514600 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 23:30:16.514650 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 23:30:16.516684 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 29 23:30:16.516730 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 29 23:30:16.519158 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 23:30:16.519232 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 23:30:16.521498 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 23:30:16.521552 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 23:30:16.524055 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 23:30:16.531379 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 29 23:30:16.536943 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 23:30:16.537048 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 29 23:30:16.538837 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 29 23:30:16.541519 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 29 23:30:16.560988 systemd[1]: Switching root. Oct 29 23:30:16.602805 systemd-journald[348]: Journal stopped Oct 29 23:30:17.394790 systemd-journald[348]: Received SIGTERM from PID 1 (systemd). Oct 29 23:30:17.394836 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 23:30:17.394854 kernel: SELinux: policy capability open_perms=1 Oct 29 23:30:17.394867 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 23:30:17.394879 kernel: SELinux: policy capability always_check_network=0 Oct 29 23:30:17.394890 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 23:30:17.394902 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 23:30:17.394912 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 23:30:17.394922 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 29 23:30:17.394931 kernel: SELinux: policy capability userspace_initial_context=0 Oct 29 23:30:17.394941 kernel: audit: type=1403 audit(1761780616.784:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 23:30:17.394954 systemd[1]: Successfully loaded SELinux policy in 65.226ms. Oct 29 23:30:17.394970 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 5.636ms. Oct 29 23:30:17.394982 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 29 23:30:17.394994 systemd[1]: Detected virtualization kvm. Oct 29 23:30:17.395005 systemd[1]: Detected architecture arm64. Oct 29 23:30:17.395016 systemd[1]: Detected first boot. Oct 29 23:30:17.395026 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 29 23:30:17.395039 zram_generator::config[1141]: No configuration found. Oct 29 23:30:17.395050 kernel: NET: Registered PF_VSOCK protocol family Oct 29 23:30:17.395061 systemd[1]: Populated /etc with preset unit settings. Oct 29 23:30:17.395071 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 23:30:17.395082 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 29 23:30:17.395094 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 23:30:17.395105 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 29 23:30:17.395116 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 29 23:30:17.395126 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 29 23:30:17.395136 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 29 23:30:17.395147 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 29 23:30:17.395160 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 29 23:30:17.395171 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 29 23:30:17.395181 systemd[1]: Created slice user.slice - User and Session Slice. Oct 29 23:30:17.395228 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 29 23:30:17.395239 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 29 23:30:17.395250 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 29 23:30:17.395260 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 29 23:30:17.395273 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 29 23:30:17.395287 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 29 23:30:17.395298 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 29 23:30:17.395308 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 29 23:30:17.395319 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 29 23:30:17.395333 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 29 23:30:17.395345 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 29 23:30:17.395356 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 29 23:30:17.395366 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 29 23:30:17.395377 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 29 23:30:17.395388 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 29 23:30:17.395399 systemd[1]: Reached target slices.target - Slice Units. Oct 29 23:30:17.395409 systemd[1]: Reached target swap.target - Swaps. Oct 29 23:30:17.395421 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 29 23:30:17.395432 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 29 23:30:17.395442 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 29 23:30:17.395455 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 29 23:30:17.395465 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 29 23:30:17.395476 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 29 23:30:17.395487 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 29 23:30:17.395499 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 29 23:30:17.395510 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 29 23:30:17.395520 systemd[1]: Mounting media.mount - External Media Directory... Oct 29 23:30:17.395531 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 29 23:30:17.395541 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 29 23:30:17.395552 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 29 23:30:17.395562 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 29 23:30:17.395574 systemd[1]: Reached target machines.target - Containers. Oct 29 23:30:17.395585 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 29 23:30:17.395596 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 23:30:17.395607 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 29 23:30:17.395618 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 29 23:30:17.395629 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 29 23:30:17.395639 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 29 23:30:17.395652 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 29 23:30:17.395662 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 29 23:30:17.395673 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 29 23:30:17.395684 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 29 23:30:17.395694 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 23:30:17.395705 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 29 23:30:17.395716 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 23:30:17.395729 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 23:30:17.395740 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 29 23:30:17.395751 kernel: ACPI: bus type drm_connector registered Oct 29 23:30:17.395760 kernel: fuse: init (API version 7.41) Oct 29 23:30:17.395770 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 29 23:30:17.395781 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 29 23:30:17.395792 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 29 23:30:17.395804 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 29 23:30:17.395815 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 29 23:30:17.395825 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 29 23:30:17.395836 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 29 23:30:17.395864 systemd-journald[1221]: Collecting audit messages is disabled. Oct 29 23:30:17.395887 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 29 23:30:17.395899 systemd-journald[1221]: Journal started Oct 29 23:30:17.395918 systemd-journald[1221]: Runtime Journal (/run/log/journal/30dc52d94fc4429aa2a6d43b1014e0aa) is 6M, max 48.5M, 42.4M free. Oct 29 23:30:17.399265 systemd[1]: Mounted media.mount - External Media Directory. Oct 29 23:30:17.399295 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 29 23:30:17.150672 systemd[1]: Queued start job for default target multi-user.target. Oct 29 23:30:17.174025 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 29 23:30:17.174469 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 23:30:17.403460 systemd[1]: Started systemd-journald.service - Journal Service. Oct 29 23:30:17.404413 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 29 23:30:17.406452 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 29 23:30:17.407809 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 29 23:30:17.409437 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 29 23:30:17.410996 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 23:30:17.411162 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 29 23:30:17.412698 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 23:30:17.412879 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 29 23:30:17.414529 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 23:30:17.414714 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 29 23:30:17.416141 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 23:30:17.416364 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 29 23:30:17.417967 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 23:30:17.418163 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 29 23:30:17.419544 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 23:30:17.419720 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 29 23:30:17.421215 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 29 23:30:17.423005 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 29 23:30:17.425536 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 29 23:30:17.427269 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 29 23:30:17.439954 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 29 23:30:17.441487 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 29 23:30:17.443893 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 29 23:30:17.445981 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 29 23:30:17.447263 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 29 23:30:17.447292 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 29 23:30:17.449218 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 29 23:30:17.450628 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 23:30:17.453121 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 29 23:30:17.455215 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 29 23:30:17.456392 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 23:30:17.457217 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 29 23:30:17.458404 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 29 23:30:17.459316 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 29 23:30:17.461308 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 29 23:30:17.466341 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 29 23:30:17.468408 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 29 23:30:17.468686 systemd-journald[1221]: Time spent on flushing to /var/log/journal/30dc52d94fc4429aa2a6d43b1014e0aa is 18.510ms for 857 entries. Oct 29 23:30:17.468686 systemd-journald[1221]: System Journal (/var/log/journal/30dc52d94fc4429aa2a6d43b1014e0aa) is 8M, max 163.5M, 155.5M free. Oct 29 23:30:17.498328 systemd-journald[1221]: Received client request to flush runtime journal. Oct 29 23:30:17.498365 kernel: loop1: detected capacity change from 0 to 100624 Oct 29 23:30:17.472296 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 29 23:30:17.473693 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 29 23:30:17.477063 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 29 23:30:17.479720 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 29 23:30:17.483004 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 29 23:30:17.495704 systemd-tmpfiles[1259]: ACLs are not supported, ignoring. Oct 29 23:30:17.495715 systemd-tmpfiles[1259]: ACLs are not supported, ignoring. Oct 29 23:30:17.495721 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 29 23:30:17.498503 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 29 23:30:17.500263 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 29 23:30:17.503541 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 29 23:30:17.507355 kernel: loop2: detected capacity change from 0 to 207008 Oct 29 23:30:17.516453 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 29 23:30:17.535067 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 29 23:30:17.538010 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 29 23:30:17.540101 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 29 23:30:17.544239 kernel: loop3: detected capacity change from 0 to 119344 Oct 29 23:30:17.552667 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 29 23:30:17.563332 systemd-tmpfiles[1279]: ACLs are not supported, ignoring. Oct 29 23:30:17.563597 systemd-tmpfiles[1279]: ACLs are not supported, ignoring. Oct 29 23:30:17.569546 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 29 23:30:17.574244 kernel: loop4: detected capacity change from 0 to 100624 Oct 29 23:30:17.580211 kernel: loop5: detected capacity change from 0 to 207008 Oct 29 23:30:17.586199 kernel: loop6: detected capacity change from 0 to 119344 Oct 29 23:30:17.589679 (sd-merge)[1284]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw'. Oct 29 23:30:17.592595 (sd-merge)[1284]: Merged extensions into '/usr'. Oct 29 23:30:17.596147 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 29 23:30:17.597549 systemd[1]: Reload requested from client PID 1258 ('systemd-sysext') (unit systemd-sysext.service)... Oct 29 23:30:17.597568 systemd[1]: Reloading... Oct 29 23:30:17.649865 systemd-resolved[1278]: Positive Trust Anchors: Oct 29 23:30:17.650156 systemd-resolved[1278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 23:30:17.650163 systemd-resolved[1278]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 29 23:30:17.650220 systemd-resolved[1278]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 29 23:30:17.654415 zram_generator::config[1316]: No configuration found. Oct 29 23:30:17.657381 systemd-resolved[1278]: Defaulting to hostname 'linux'. Oct 29 23:30:17.787040 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 23:30:17.787248 systemd[1]: Reloading finished in 189 ms. Oct 29 23:30:17.819899 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 29 23:30:17.821483 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 29 23:30:17.824622 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 29 23:30:17.847470 systemd[1]: Starting ensure-sysext.service... Oct 29 23:30:17.851355 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 29 23:30:17.859733 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 29 23:30:17.861937 systemd[1]: Reload requested from client PID 1350 ('systemctl') (unit ensure-sysext.service)... Oct 29 23:30:17.861958 systemd[1]: Reloading... Oct 29 23:30:17.866414 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 29 23:30:17.866462 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 29 23:30:17.866788 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 29 23:30:17.866978 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 29 23:30:17.867643 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 23:30:17.867836 systemd-tmpfiles[1351]: ACLs are not supported, ignoring. Oct 29 23:30:17.867884 systemd-tmpfiles[1351]: ACLs are not supported, ignoring. Oct 29 23:30:17.874695 systemd-tmpfiles[1351]: Detected autofs mount point /boot during canonicalization of boot. Oct 29 23:30:17.874709 systemd-tmpfiles[1351]: Skipping /boot Oct 29 23:30:17.880560 systemd-tmpfiles[1351]: Detected autofs mount point /boot during canonicalization of boot. Oct 29 23:30:17.880574 systemd-tmpfiles[1351]: Skipping /boot Oct 29 23:30:17.906229 zram_generator::config[1381]: No configuration found. Oct 29 23:30:18.038403 systemd[1]: Reloading finished in 176 ms. Oct 29 23:30:18.058787 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 29 23:30:18.078110 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 29 23:30:18.080193 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 29 23:30:18.090499 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 29 23:30:18.092967 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 29 23:30:18.096344 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 29 23:30:18.098958 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 29 23:30:18.107046 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 23:30:18.108433 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 29 23:30:18.112581 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 29 23:30:18.116567 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 29 23:30:18.117845 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 23:30:18.117993 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 29 23:30:18.120938 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 23:30:18.121124 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 23:30:18.121256 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 29 23:30:18.124656 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 29 23:30:18.126535 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 29 23:30:18.126615 systemd-udevd[1421]: Using default interface naming scheme 'v257'. Oct 29 23:30:18.129057 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 29 23:30:18.129211 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 29 23:30:18.133258 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 29 23:30:18.137367 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 29 23:30:18.137541 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 29 23:30:18.140910 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 29 23:30:18.143145 systemd[1]: Finished ensure-sysext.service. Oct 29 23:30:18.144577 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 23:30:18.144826 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 29 23:30:18.148059 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 29 23:30:18.157113 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 29 23:30:18.161965 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 29 23:30:18.164863 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 29 23:30:18.167670 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 29 23:30:18.167881 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 29 23:30:18.169847 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 29 23:30:18.170059 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 29 23:30:18.174746 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 29 23:30:18.182291 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 29 23:30:18.182332 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 29 23:30:18.195048 augenrules[1479]: No rules Oct 29 23:30:18.197856 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 23:30:18.198377 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 29 23:30:18.235757 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 29 23:30:18.238551 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 29 23:30:18.238591 systemd[1]: Reached target time-set.target - System Time Set. Oct 29 23:30:18.245467 systemd-networkd[1447]: lo: Link UP Oct 29 23:30:18.245762 systemd-networkd[1447]: lo: Gained carrier Oct 29 23:30:18.246980 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 29 23:30:18.248915 systemd[1]: Reached target network.target - Network. Oct 29 23:30:18.252127 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 29 23:30:18.256462 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 29 23:30:18.287647 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 29 23:30:18.291440 systemd-networkd[1447]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 23:30:18.291451 systemd-networkd[1447]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 29 23:30:18.292339 systemd-networkd[1447]: eth0: Link UP Oct 29 23:30:18.292453 systemd-networkd[1447]: eth0: Gained carrier Oct 29 23:30:18.292473 systemd-networkd[1447]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 29 23:30:18.301764 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 29 23:30:18.305014 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 29 23:30:18.313284 systemd-networkd[1447]: eth0: DHCPv4 address 10.0.0.44/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 29 23:30:18.314581 systemd-timesyncd[1448]: Network configuration changed, trying to establish connection. Oct 29 23:30:18.315431 systemd-timesyncd[1448]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 29 23:30:18.315488 systemd-timesyncd[1448]: Initial clock synchronization to Wed 2025-10-29 23:30:18.340698 UTC. Oct 29 23:30:18.333744 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 29 23:30:18.396146 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 29 23:30:18.445223 ldconfig[1418]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 29 23:30:18.449341 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 29 23:30:18.453288 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 29 23:30:18.456479 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 29 23:30:18.476358 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 29 23:30:18.477903 systemd[1]: Reached target sysinit.target - System Initialization. Oct 29 23:30:18.479319 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 29 23:30:18.480788 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 23:30:18.482499 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 29 23:30:18.483834 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 29 23:30:18.485261 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 29 23:30:18.486624 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 29 23:30:18.486661 systemd[1]: Reached target paths.target - Path Units. Oct 29 23:30:18.487682 systemd[1]: Reached target timers.target - Timer Units. Oct 29 23:30:18.489591 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 29 23:30:18.492324 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 29 23:30:18.495178 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 29 23:30:18.496847 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 29 23:30:18.498276 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 29 23:30:18.505165 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 29 23:30:18.506628 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 29 23:30:18.508469 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 29 23:30:18.509709 systemd[1]: Reached target sockets.target - Socket Units. Oct 29 23:30:18.510758 systemd[1]: Reached target basic.target - Basic System. Oct 29 23:30:18.511835 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 29 23:30:18.511872 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 29 23:30:18.513019 systemd[1]: Starting containerd.service - containerd container runtime... Oct 29 23:30:18.515301 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 29 23:30:18.517420 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 29 23:30:18.519994 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 29 23:30:18.522549 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 29 23:30:18.523695 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 29 23:30:18.524890 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 29 23:30:18.527360 jq[1529]: false Oct 29 23:30:18.528439 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 29 23:30:18.530720 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 29 23:30:18.534008 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 29 23:30:18.536304 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 29 23:30:18.536722 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 23:30:18.538227 extend-filesystems[1530]: Found /dev/vda6 Oct 29 23:30:18.537414 systemd[1]: Starting update-engine.service - Update Engine... Oct 29 23:30:18.540327 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 29 23:30:18.541605 extend-filesystems[1530]: Found /dev/vda9 Oct 29 23:30:18.544466 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 29 23:30:18.547296 extend-filesystems[1530]: Checking size of /dev/vda9 Oct 29 23:30:18.548241 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 29 23:30:18.551527 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 29 23:30:18.551965 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 23:30:18.552979 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 29 23:30:18.555256 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 23:30:18.558032 jq[1542]: true Oct 29 23:30:18.555443 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 29 23:30:18.566841 extend-filesystems[1530]: Resized partition /dev/vda9 Oct 29 23:30:18.568560 extend-filesystems[1568]: resize2fs 1.47.3 (8-Jul-2025) Oct 29 23:30:18.573650 (ntainerd)[1556]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 29 23:30:18.577239 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 1784827 blocks Oct 29 23:30:18.581562 jq[1555]: true Oct 29 23:30:18.584052 update_engine[1539]: I20251029 23:30:18.583786 1539 main.cc:92] Flatcar Update Engine starting Oct 29 23:30:18.597377 kernel: EXT4-fs (vda9): resized filesystem to 1784827 Oct 29 23:30:18.611897 extend-filesystems[1568]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 29 23:30:18.611897 extend-filesystems[1568]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 29 23:30:18.611897 extend-filesystems[1568]: The filesystem on /dev/vda9 is now 1784827 (4k) blocks long. Oct 29 23:30:18.622003 extend-filesystems[1530]: Resized filesystem in /dev/vda9 Oct 29 23:30:18.612985 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 23:30:18.613255 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 29 23:30:18.625640 dbus-daemon[1527]: [system] SELinux support is enabled Oct 29 23:30:18.626748 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 29 23:30:18.628667 update_engine[1539]: I20251029 23:30:18.628612 1539 update_check_scheduler.cc:74] Next update check in 5m40s Oct 29 23:30:18.633788 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 29 23:30:18.633820 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 29 23:30:18.635356 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 29 23:30:18.635383 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 29 23:30:18.636756 systemd[1]: Started update-engine.service - Update Engine. Oct 29 23:30:18.639251 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 29 23:30:18.657831 systemd-logind[1537]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 23:30:18.658310 systemd-logind[1537]: New seat seat0. Oct 29 23:30:18.659059 systemd[1]: Started systemd-logind.service - User Login Management. Oct 29 23:30:18.664900 bash[1591]: Updated "/home/core/.ssh/authorized_keys" Oct 29 23:30:18.665921 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 29 23:30:18.669940 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 29 23:30:18.689379 locksmithd[1587]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 23:30:18.766056 containerd[1556]: time="2025-10-29T23:30:18Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 29 23:30:18.767178 containerd[1556]: time="2025-10-29T23:30:18.767134400Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 29 23:30:18.776381 containerd[1556]: time="2025-10-29T23:30:18.776328960Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.68µs" Oct 29 23:30:18.776381 containerd[1556]: time="2025-10-29T23:30:18.776368520Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 29 23:30:18.776440 containerd[1556]: time="2025-10-29T23:30:18.776387200Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 29 23:30:18.776588 containerd[1556]: time="2025-10-29T23:30:18.776554960Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 29 23:30:18.776588 containerd[1556]: time="2025-10-29T23:30:18.776578520Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 29 23:30:18.776651 containerd[1556]: time="2025-10-29T23:30:18.776602360Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 29 23:30:18.776670 containerd[1556]: time="2025-10-29T23:30:18.776651320Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 29 23:30:18.776670 containerd[1556]: time="2025-10-29T23:30:18.776663160Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 29 23:30:18.776889 containerd[1556]: time="2025-10-29T23:30:18.776852040Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 29 23:30:18.776889 containerd[1556]: time="2025-10-29T23:30:18.776874960Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 29 23:30:18.776889 containerd[1556]: time="2025-10-29T23:30:18.776886120Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 29 23:30:18.777005 containerd[1556]: time="2025-10-29T23:30:18.776894080Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 29 23:30:18.777005 containerd[1556]: time="2025-10-29T23:30:18.776962120Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 29 23:30:18.777171 containerd[1556]: time="2025-10-29T23:30:18.777133360Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 29 23:30:18.777219 containerd[1556]: time="2025-10-29T23:30:18.777168920Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 29 23:30:18.777219 containerd[1556]: time="2025-10-29T23:30:18.777178560Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 29 23:30:18.777276 containerd[1556]: time="2025-10-29T23:30:18.777258640Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 29 23:30:18.777535 containerd[1556]: time="2025-10-29T23:30:18.777506280Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 29 23:30:18.777609 containerd[1556]: time="2025-10-29T23:30:18.777583920Z" level=info msg="metadata content store policy set" policy=shared Oct 29 23:30:18.780759 containerd[1556]: time="2025-10-29T23:30:18.780717600Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 29 23:30:18.780786 containerd[1556]: time="2025-10-29T23:30:18.780778520Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 29 23:30:18.780803 containerd[1556]: time="2025-10-29T23:30:18.780793560Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 29 23:30:18.780821 containerd[1556]: time="2025-10-29T23:30:18.780805920Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 29 23:30:18.780821 containerd[1556]: time="2025-10-29T23:30:18.780818360Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 29 23:30:18.780853 containerd[1556]: time="2025-10-29T23:30:18.780830640Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 29 23:30:18.780880 containerd[1556]: time="2025-10-29T23:30:18.780843640Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 29 23:30:18.780880 containerd[1556]: time="2025-10-29T23:30:18.780872080Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 29 23:30:18.780912 containerd[1556]: time="2025-10-29T23:30:18.780885280Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 29 23:30:18.780912 containerd[1556]: time="2025-10-29T23:30:18.780895200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 29 23:30:18.780912 containerd[1556]: time="2025-10-29T23:30:18.780904640Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 29 23:30:18.780957 containerd[1556]: time="2025-10-29T23:30:18.780916960Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 29 23:30:18.781071 containerd[1556]: time="2025-10-29T23:30:18.781039360Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 29 23:30:18.781096 containerd[1556]: time="2025-10-29T23:30:18.781070520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 29 23:30:18.781096 containerd[1556]: time="2025-10-29T23:30:18.781086720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 29 23:30:18.781128 containerd[1556]: time="2025-10-29T23:30:18.781097640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 29 23:30:18.781128 containerd[1556]: time="2025-10-29T23:30:18.781107440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 29 23:30:18.781128 containerd[1556]: time="2025-10-29T23:30:18.781117320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 29 23:30:18.781178 containerd[1556]: time="2025-10-29T23:30:18.781128920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 29 23:30:18.781178 containerd[1556]: time="2025-10-29T23:30:18.781139240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 29 23:30:18.781178 containerd[1556]: time="2025-10-29T23:30:18.781150160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 29 23:30:18.781178 containerd[1556]: time="2025-10-29T23:30:18.781165160Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 29 23:30:18.781178 containerd[1556]: time="2025-10-29T23:30:18.781175400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 29 23:30:18.781434 containerd[1556]: time="2025-10-29T23:30:18.781401400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 29 23:30:18.781434 containerd[1556]: time="2025-10-29T23:30:18.781425840Z" level=info msg="Start snapshots syncer" Oct 29 23:30:18.781476 containerd[1556]: time="2025-10-29T23:30:18.781453560Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 29 23:30:18.781723 containerd[1556]: time="2025-10-29T23:30:18.781672400Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 29 23:30:18.781816 containerd[1556]: time="2025-10-29T23:30:18.781730360Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 29 23:30:18.781920 containerd[1556]: time="2025-10-29T23:30:18.781898000Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 29 23:30:18.782051 containerd[1556]: time="2025-10-29T23:30:18.782029720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 29 23:30:18.782077 containerd[1556]: time="2025-10-29T23:30:18.782064440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 29 23:30:18.782096 containerd[1556]: time="2025-10-29T23:30:18.782076640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 29 23:30:18.782096 containerd[1556]: time="2025-10-29T23:30:18.782089160Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 29 23:30:18.782132 containerd[1556]: time="2025-10-29T23:30:18.782100600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 29 23:30:18.782132 containerd[1556]: time="2025-10-29T23:30:18.782111680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 29 23:30:18.782132 containerd[1556]: time="2025-10-29T23:30:18.782126760Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 29 23:30:18.782179 containerd[1556]: time="2025-10-29T23:30:18.782151240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 29 23:30:18.782179 containerd[1556]: time="2025-10-29T23:30:18.782163160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 29 23:30:18.782248 containerd[1556]: time="2025-10-29T23:30:18.782177840Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 29 23:30:18.782283 containerd[1556]: time="2025-10-29T23:30:18.782266480Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 29 23:30:18.782306 containerd[1556]: time="2025-10-29T23:30:18.782286480Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 29 23:30:18.782306 containerd[1556]: time="2025-10-29T23:30:18.782296640Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 29 23:30:18.782338 containerd[1556]: time="2025-10-29T23:30:18.782305520Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 29 23:30:18.782338 containerd[1556]: time="2025-10-29T23:30:18.782313520Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 29 23:30:18.782338 containerd[1556]: time="2025-10-29T23:30:18.782322720Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 29 23:30:18.782338 containerd[1556]: time="2025-10-29T23:30:18.782333000Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 29 23:30:18.782420 containerd[1556]: time="2025-10-29T23:30:18.782410080Z" level=info msg="runtime interface created" Oct 29 23:30:18.782420 containerd[1556]: time="2025-10-29T23:30:18.782418520Z" level=info msg="created NRI interface" Oct 29 23:30:18.782454 containerd[1556]: time="2025-10-29T23:30:18.782428480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 29 23:30:18.782454 containerd[1556]: time="2025-10-29T23:30:18.782442160Z" level=info msg="Connect containerd service" Oct 29 23:30:18.782489 containerd[1556]: time="2025-10-29T23:30:18.782468160Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 29 23:30:18.783535 containerd[1556]: time="2025-10-29T23:30:18.783491960Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 29 23:30:18.847874 containerd[1556]: time="2025-10-29T23:30:18.847743920Z" level=info msg="Start subscribing containerd event" Oct 29 23:30:18.847874 containerd[1556]: time="2025-10-29T23:30:18.847817480Z" level=info msg="Start recovering state" Oct 29 23:30:18.847978 containerd[1556]: time="2025-10-29T23:30:18.847905600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 29 23:30:18.847978 containerd[1556]: time="2025-10-29T23:30:18.847923520Z" level=info msg="Start event monitor" Oct 29 23:30:18.847978 containerd[1556]: time="2025-10-29T23:30:18.847944080Z" level=info msg="Start cni network conf syncer for default" Oct 29 23:30:18.847978 containerd[1556]: time="2025-10-29T23:30:18.847951400Z" level=info msg="Start streaming server" Oct 29 23:30:18.847978 containerd[1556]: time="2025-10-29T23:30:18.847956320Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 29 23:30:18.848074 containerd[1556]: time="2025-10-29T23:30:18.847959960Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 29 23:30:18.848074 containerd[1556]: time="2025-10-29T23:30:18.848003880Z" level=info msg="runtime interface starting up..." Oct 29 23:30:18.848074 containerd[1556]: time="2025-10-29T23:30:18.848009600Z" level=info msg="starting plugins..." Oct 29 23:30:18.848074 containerd[1556]: time="2025-10-29T23:30:18.848026760Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 29 23:30:18.848135 containerd[1556]: time="2025-10-29T23:30:18.848123680Z" level=info msg="containerd successfully booted in 0.082427s" Oct 29 23:30:18.848304 systemd[1]: Started containerd.service - containerd container runtime. Oct 29 23:30:19.967313 systemd-networkd[1447]: eth0: Gained IPv6LL Oct 29 23:30:19.973035 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 29 23:30:19.975259 systemd[1]: Reached target network-online.target - Network is Online. Oct 29 23:30:19.978467 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 29 23:30:19.982373 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 29 23:30:20.005969 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 29 23:30:20.045384 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 29 23:30:20.051473 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 29 23:30:20.051767 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 29 23:30:20.055423 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 29 23:30:20.527429 sshd_keygen[1551]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 29 23:30:20.548299 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 29 23:30:20.553455 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 29 23:30:20.574099 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 23:30:20.575276 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 29 23:30:20.579143 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 29 23:30:20.598092 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 29 23:30:20.601618 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 23:30:20.605240 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 29 23:30:20.605661 (kubelet)[1655]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 29 23:30:20.608037 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 29 23:30:20.609587 systemd[1]: Reached target getty.target - Login Prompts. Oct 29 23:30:20.610769 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 29 23:30:20.612066 systemd[1]: Startup finished in 1.201s (kernel) + 4.669s (initrd) + 3.893s (userspace) = 9.763s. Oct 29 23:30:20.976198 kubelet[1655]: E1029 23:30:20.976134 1655 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Oct 29 23:30:20.978492 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 29 23:30:20.978630 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 29 23:30:20.980274 systemd[1]: kubelet.service: Consumed 747ms CPU time, 256.4M memory peak. Oct 29 23:30:23.352838 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 29 23:30:23.353919 systemd[1]: Started sshd@0-10.0.0.44:22-10.0.0.1:57648.service - OpenSSH per-connection server daemon (10.0.0.1:57648). Oct 29 23:30:23.439400 sshd[1671]: Accepted publickey for core from 10.0.0.1 port 57648 ssh2: RSA SHA256:4Dr3tbK61/FsUQz8dYLzShceLjKIFoQvj0rUu7yBHE4 Oct 29 23:30:23.441265 sshd-session[1671]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 23:30:23.447404 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 29 23:30:23.448364 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 29 23:30:23.455428 systemd-logind[1537]: New session 1 of user core. Oct 29 23:30:23.470893 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 29 23:30:23.473458 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 29 23:30:23.492634 (systemd)[1676]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 23:30:23.494999 systemd-logind[1537]: New session c1 of user core. Oct 29 23:30:23.599431 systemd[1676]: Queued start job for default target default.target. Oct 29 23:30:23.606127 systemd[1676]: Created slice app.slice - User Application Slice. Oct 29 23:30:23.606159 systemd[1676]: Reached target paths.target - Paths. Oct 29 23:30:23.606225 systemd[1676]: Reached target timers.target - Timers. Oct 29 23:30:23.607541 systemd[1676]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 29 23:30:23.618476 systemd[1676]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 29 23:30:23.618607 systemd[1676]: Reached target sockets.target - Sockets. Oct 29 23:30:23.618657 systemd[1676]: Reached target basic.target - Basic System. Oct 29 23:30:23.618695 systemd[1676]: Reached target default.target - Main User Target. Oct 29 23:30:23.618723 systemd[1676]: Startup finished in 117ms. Oct 29 23:30:23.618979 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 29 23:30:23.620560 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 29 23:30:23.690013 systemd[1]: Started sshd@1-10.0.0.44:22-10.0.0.1:57662.service - OpenSSH per-connection server daemon (10.0.0.1:57662). Oct 29 23:30:23.764934 sshd[1687]: Accepted publickey for core from 10.0.0.1 port 57662 ssh2: RSA SHA256:4Dr3tbK61/FsUQz8dYLzShceLjKIFoQvj0rUu7yBHE4 Oct 29 23:30:23.766356 sshd-session[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 23:30:23.770216 systemd-logind[1537]: New session 2 of user core. Oct 29 23:30:23.781391 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 29 23:30:23.833547 sshd[1690]: Connection closed by 10.0.0.1 port 57662 Oct 29 23:30:23.834113 sshd-session[1687]: pam_unix(sshd:session): session closed for user core Oct 29 23:30:23.846273 systemd[1]: sshd@1-10.0.0.44:22-10.0.0.1:57662.service: Deactivated successfully. Oct 29 23:30:23.848688 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 23:30:23.849970 systemd-logind[1537]: Session 2 logged out. Waiting for processes to exit. Oct 29 23:30:23.852725 systemd[1]: Started sshd@2-10.0.0.44:22-10.0.0.1:57674.service - OpenSSH per-connection server daemon (10.0.0.1:57674). Oct 29 23:30:23.853297 systemd-logind[1537]: Removed session 2. Oct 29 23:30:23.914269 sshd[1696]: Accepted publickey for core from 10.0.0.1 port 57674 ssh2: RSA SHA256:4Dr3tbK61/FsUQz8dYLzShceLjKIFoQvj0rUu7yBHE4 Oct 29 23:30:23.915623 sshd-session[1696]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 23:30:23.921085 systemd-logind[1537]: New session 3 of user core. Oct 29 23:30:23.935428 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 29 23:30:23.983609 sshd[1699]: Connection closed by 10.0.0.1 port 57674 Oct 29 23:30:23.984491 sshd-session[1696]: pam_unix(sshd:session): session closed for user core Oct 29 23:30:23.994272 systemd[1]: sshd@2-10.0.0.44:22-10.0.0.1:57674.service: Deactivated successfully. Oct 29 23:30:23.995755 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 23:30:23.996429 systemd-logind[1537]: Session 3 logged out. Waiting for processes to exit. Oct 29 23:30:23.998531 systemd[1]: Started sshd@3-10.0.0.44:22-10.0.0.1:57684.service - OpenSSH per-connection server daemon (10.0.0.1:57684). Oct 29 23:30:23.999576 systemd-logind[1537]: Removed session 3. Oct 29 23:30:24.060295 sshd[1705]: Accepted publickey for core from 10.0.0.1 port 57684 ssh2: RSA SHA256:4Dr3tbK61/FsUQz8dYLzShceLjKIFoQvj0rUu7yBHE4 Oct 29 23:30:24.061730 sshd-session[1705]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 23:30:24.065980 systemd-logind[1537]: New session 4 of user core. Oct 29 23:30:24.080521 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 29 23:30:24.132241 sshd[1708]: Connection closed by 10.0.0.1 port 57684 Oct 29 23:30:24.132752 sshd-session[1705]: pam_unix(sshd:session): session closed for user core Oct 29 23:30:24.144227 systemd[1]: sshd@3-10.0.0.44:22-10.0.0.1:57684.service: Deactivated successfully. Oct 29 23:30:24.145614 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 23:30:24.148168 systemd-logind[1537]: Session 4 logged out. Waiting for processes to exit. Oct 29 23:30:24.149289 systemd[1]: Started sshd@4-10.0.0.44:22-10.0.0.1:57692.service - OpenSSH per-connection server daemon (10.0.0.1:57692). Oct 29 23:30:24.150319 systemd-logind[1537]: Removed session 4. Oct 29 23:30:24.205962 sshd[1714]: Accepted publickey for core from 10.0.0.1 port 57692 ssh2: RSA SHA256:4Dr3tbK61/FsUQz8dYLzShceLjKIFoQvj0rUu7yBHE4 Oct 29 23:30:24.207032 sshd-session[1714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 29 23:30:24.211376 systemd-logind[1537]: New session 5 of user core. Oct 29 23:30:24.222364 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 29 23:30:24.280255 sudo[1718]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 29 23:30:24.281145 sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 29 23:30:24.292633 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 29 23:30:24.367376 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 29 23:30:24.367620 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 29 23:30:24.811007 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 23:30:24.811151 systemd[1]: kubelet.service: Consumed 747ms CPU time, 256.4M memory peak. Oct 29 23:30:24.813084 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 29 23:30:24.836896 systemd[1]: Reload requested from client PID 1760 ('systemctl') (unit session-5.scope)... Oct 29 23:30:24.836914 systemd[1]: Reloading... Oct 29 23:30:24.907222 zram_generator::config[1802]: No configuration found. Oct 29 23:30:25.094327 systemd[1]: Reloading finished in 256 ms. Oct 29 23:30:25.141073 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 29 23:30:25.141136 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 29 23:30:25.143249 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 23:30:25.143299 systemd[1]: kubelet.service: Consumed 102ms CPU time, 95.1M memory peak. Oct 29 23:30:25.146044 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 29 23:30:25.285717 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 29 23:30:25.290071 (kubelet)[1848]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 29 23:30:25.329646 kubelet[1848]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 29 23:30:25.329646 kubelet[1848]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 29 23:30:25.329646 kubelet[1848]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 29 23:30:25.329975 kubelet[1848]: I1029 23:30:25.329768 1848 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" Oct 29 23:30:26.341078 kubelet[1848]: I1029 23:30:26.341024 1848 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Oct 29 23:30:26.341078 kubelet[1848]: I1029 23:30:26.341063 1848 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 29 23:30:26.341456 kubelet[1848]: I1029 23:30:26.341366 1848 server.go:954] "Client rotation is on, will bootstrap in background" Oct 29 23:30:26.366961 kubelet[1848]: I1029 23:30:26.366788 1848 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 29 23:30:26.373684 kubelet[1848]: I1029 23:30:26.373639 1848 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 29 23:30:26.376958 kubelet[1848]: I1029 23:30:26.376932 1848 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 29 23:30:26.377659 kubelet[1848]: I1029 23:30:26.377597 1848 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 29 23:30:26.377860 kubelet[1848]: I1029 23:30:26.377649 1848 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.44","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 29 23:30:26.377995 kubelet[1848]: I1029 23:30:26.377972 1848 topology_manager.go:138] "Creating topology manager with none policy" Oct 29 23:30:26.377995 kubelet[1848]: I1029 23:30:26.377981 1848 container_manager_linux.go:304] "Creating device plugin manager" Oct 29 23:30:26.378237 kubelet[1848]: I1029 23:30:26.378218 1848 state_mem.go:36] "Initialized new in-memory state store" Oct 29 23:30:26.381224 kubelet[1848]: I1029 23:30:26.381181 1848 kubelet.go:446] "Attempting to sync node with API server" Oct 29 23:30:26.381269 kubelet[1848]: I1029 23:30:26.381239 1848 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 29 23:30:26.381269 kubelet[1848]: I1029 23:30:26.381268 1848 kubelet.go:352] "Adding apiserver pod source" Oct 29 23:30:26.381305 kubelet[1848]: I1029 23:30:26.381280 1848 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 29 23:30:26.381459 kubelet[1848]: E1029 23:30:26.381432 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:26.381553 kubelet[1848]: E1029 23:30:26.381540 1848 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:26.383876 kubelet[1848]: I1029 23:30:26.383852 1848 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Oct 29 23:30:26.385226 kubelet[1848]: I1029 23:30:26.384801 1848 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Oct 29 23:30:26.385226 kubelet[1848]: W1029 23:30:26.384934 1848 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 29 23:30:26.385927 kubelet[1848]: I1029 23:30:26.385891 1848 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 29 23:30:26.385998 kubelet[1848]: I1029 23:30:26.385936 1848 server.go:1287] "Started kubelet" Oct 29 23:30:26.386114 kubelet[1848]: I1029 23:30:26.386078 1848 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Oct 29 23:30:26.386766 kubelet[1848]: I1029 23:30:26.386711 1848 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 29 23:30:26.387020 kubelet[1848]: I1029 23:30:26.386974 1848 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 29 23:30:26.388297 kubelet[1848]: I1029 23:30:26.387928 1848 server.go:479] "Adding debug handlers to kubelet server" Oct 29 23:30:26.389109 kubelet[1848]: I1029 23:30:26.389071 1848 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 29 23:30:26.390199 kubelet[1848]: I1029 23:30:26.390136 1848 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 29 23:30:26.390311 kubelet[1848]: I1029 23:30:26.390293 1848 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 29 23:30:26.390580 kubelet[1848]: E1029 23:30:26.390554 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:26.390865 kubelet[1848]: I1029 23:30:26.390843 1848 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 29 23:30:26.390923 kubelet[1848]: I1029 23:30:26.390910 1848 reconciler.go:26] "Reconciler: start to sync state" Oct 29 23:30:26.392850 kubelet[1848]: W1029 23:30:26.392817 1848 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 Oct 29 23:30:26.393023 kubelet[1848]: E1029 23:30:26.392997 1848 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" Oct 29 23:30:26.393728 kubelet[1848]: W1029 23:30:26.393687 1848 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.44" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Oct 29 23:30:26.393806 kubelet[1848]: E1029 23:30:26.393742 1848 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.44\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Oct 29 23:30:26.393931 kubelet[1848]: W1029 23:30:26.393875 1848 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 Oct 29 23:30:26.394099 kubelet[1848]: E1029 23:30:26.394078 1848 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" Oct 29 23:30:26.394797 kubelet[1848]: I1029 23:30:26.394679 1848 factory.go:221] Registration of the systemd container factory successfully Oct 29 23:30:26.395043 kubelet[1848]: I1029 23:30:26.394955 1848 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 Oct 29 23:30:26.395487 kubelet[1848]: E1029 23:30:26.395449 1848 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 29 23:30:26.399241 kubelet[1848]: I1029 23:30:26.398425 1848 factory.go:221] Registration of the containerd container factory successfully Oct 29 23:30:26.405289 kubelet[1848]: E1029 23:30:26.405236 1848 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.44\" not found" node="10.0.0.44" Oct 29 23:30:26.407645 kubelet[1848]: I1029 23:30:26.407621 1848 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 29 23:30:26.407645 kubelet[1848]: I1029 23:30:26.407640 1848 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 29 23:30:26.407760 kubelet[1848]: I1029 23:30:26.407676 1848 state_mem.go:36] "Initialized new in-memory state store" Oct 29 23:30:26.491024 kubelet[1848]: E1029 23:30:26.490980 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:26.496617 kubelet[1848]: I1029 23:30:26.496596 1848 policy_none.go:49] "None policy: Start" Oct 29 23:30:26.496617 kubelet[1848]: I1029 23:30:26.496621 1848 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 29 23:30:26.496711 kubelet[1848]: I1029 23:30:26.496634 1848 state_mem.go:35] "Initializing new in-memory state store" Oct 29 23:30:26.502821 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 29 23:30:26.518283 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 29 23:30:26.520605 kubelet[1848]: I1029 23:30:26.520568 1848 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Oct 29 23:30:26.522126 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 29 23:30:26.522400 kubelet[1848]: I1029 23:30:26.522378 1848 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Oct 29 23:30:26.522518 kubelet[1848]: I1029 23:30:26.522506 1848 status_manager.go:227] "Starting to sync pod status with apiserver" Oct 29 23:30:26.523200 kubelet[1848]: I1029 23:30:26.523065 1848 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 29 23:30:26.523200 kubelet[1848]: I1029 23:30:26.523148 1848 kubelet.go:2382] "Starting kubelet main sync loop" Oct 29 23:30:26.523336 kubelet[1848]: E1029 23:30:26.523304 1848 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]" Oct 29 23:30:26.533473 kubelet[1848]: I1029 23:30:26.533438 1848 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Oct 29 23:30:26.533812 kubelet[1848]: I1029 23:30:26.533793 1848 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 29 23:30:26.533985 kubelet[1848]: I1029 23:30:26.533947 1848 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 29 23:30:26.534177 kubelet[1848]: I1029 23:30:26.534150 1848 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 29 23:30:26.535327 kubelet[1848]: E1029 23:30:26.535301 1848 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 29 23:30:26.535385 kubelet[1848]: E1029 23:30:26.535344 1848 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.44\" not found" Oct 29 23:30:26.636726 kubelet[1848]: I1029 23:30:26.636601 1848 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.44" Oct 29 23:30:26.644436 kubelet[1848]: I1029 23:30:26.644304 1848 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.44" Oct 29 23:30:26.644436 kubelet[1848]: E1029 23:30:26.644340 1848 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.44\": node \"10.0.0.44\" not found" Oct 29 23:30:26.662433 kubelet[1848]: E1029 23:30:26.662363 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:26.763096 kubelet[1848]: E1029 23:30:26.763047 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:26.822043 sudo[1718]: pam_unix(sudo:session): session closed for user root Oct 29 23:30:26.823694 sshd[1717]: Connection closed by 10.0.0.1 port 57692 Oct 29 23:30:26.824099 sshd-session[1714]: pam_unix(sshd:session): session closed for user core Oct 29 23:30:26.828124 systemd[1]: sshd@4-10.0.0.44:22-10.0.0.1:57692.service: Deactivated successfully. Oct 29 23:30:26.830007 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 23:30:26.830243 systemd[1]: session-5.scope: Consumed 408ms CPU time, 74.8M memory peak. Oct 29 23:30:26.831263 systemd-logind[1537]: Session 5 logged out. Waiting for processes to exit. Oct 29 23:30:26.832451 systemd-logind[1537]: Removed session 5. Oct 29 23:30:26.863229 kubelet[1848]: E1029 23:30:26.863166 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:26.964004 kubelet[1848]: E1029 23:30:26.963859 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.064498 kubelet[1848]: E1029 23:30:27.064412 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.165066 kubelet[1848]: E1029 23:30:27.165007 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.265673 kubelet[1848]: E1029 23:30:27.265548 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.344257 kubelet[1848]: I1029 23:30:27.344204 1848 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 29 23:30:27.344616 kubelet[1848]: W1029 23:30:27.344392 1848 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 Oct 29 23:30:27.366571 kubelet[1848]: E1029 23:30:27.366521 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.381808 kubelet[1848]: E1029 23:30:27.381764 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:27.466884 kubelet[1848]: E1029 23:30:27.466829 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.568046 kubelet[1848]: E1029 23:30:27.567845 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.668489 kubelet[1848]: E1029 23:30:27.668416 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.769032 kubelet[1848]: E1029 23:30:27.768980 1848 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.44\" not found" Oct 29 23:30:27.870303 kubelet[1848]: I1029 23:30:27.870215 1848 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 29 23:30:27.870663 containerd[1556]: time="2025-10-29T23:30:27.870618572Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 29 23:30:27.871085 kubelet[1848]: I1029 23:30:27.870828 1848 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 29 23:30:28.382444 kubelet[1848]: E1029 23:30:28.382408 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:28.383608 kubelet[1848]: I1029 23:30:28.383585 1848 apiserver.go:52] "Watching apiserver" Oct 29 23:30:28.391139 kubelet[1848]: I1029 23:30:28.391108 1848 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 29 23:30:28.393761 systemd[1]: Created slice kubepods-besteffort-podcd1accd1_e72d_4089_8851_927c157e026c.slice - libcontainer container kubepods-besteffort-podcd1accd1_e72d_4089_8851_927c157e026c.slice. Oct 29 23:30:28.401544 kubelet[1848]: I1029 23:30:28.401440 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/cd1accd1-e72d-4089-8851-927c157e026c-lib-modules\") pod \"kube-proxy-6sffm\" (UID: \"cd1accd1-e72d-4089-8851-927c157e026c\") " pod="kube-system/kube-proxy-6sffm" Oct 29 23:30:28.401544 kubelet[1848]: I1029 23:30:28.401478 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/f2e6c42c-517b-4a28-8fbf-c92bac82be4c-cni-plugin\") pod \"kube-flannel-ds-4mqt9\" (UID: \"f2e6c42c-517b-4a28-8fbf-c92bac82be4c\") " pod="kube-flannel/kube-flannel-ds-4mqt9" Oct 29 23:30:28.401668 kubelet[1848]: I1029 23:30:28.401559 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/f2e6c42c-517b-4a28-8fbf-c92bac82be4c-cni\") pod \"kube-flannel-ds-4mqt9\" (UID: \"f2e6c42c-517b-4a28-8fbf-c92bac82be4c\") " pod="kube-flannel/kube-flannel-ds-4mqt9" Oct 29 23:30:28.401668 kubelet[1848]: I1029 23:30:28.401611 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/cd1accd1-e72d-4089-8851-927c157e026c-xtables-lock\") pod \"kube-proxy-6sffm\" (UID: \"cd1accd1-e72d-4089-8851-927c157e026c\") " pod="kube-system/kube-proxy-6sffm" Oct 29 23:30:28.401719 kubelet[1848]: I1029 23:30:28.401673 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fkx42\" (UniqueName: \"kubernetes.io/projected/cd1accd1-e72d-4089-8851-927c157e026c-kube-api-access-fkx42\") pod \"kube-proxy-6sffm\" (UID: \"cd1accd1-e72d-4089-8851-927c157e026c\") " pod="kube-system/kube-proxy-6sffm" Oct 29 23:30:28.401719 kubelet[1848]: I1029 23:30:28.401695 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/f2e6c42c-517b-4a28-8fbf-c92bac82be4c-run\") pod \"kube-flannel-ds-4mqt9\" (UID: \"f2e6c42c-517b-4a28-8fbf-c92bac82be4c\") " pod="kube-flannel/kube-flannel-ds-4mqt9" Oct 29 23:30:28.401771 kubelet[1848]: I1029 23:30:28.401722 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/f2e6c42c-517b-4a28-8fbf-c92bac82be4c-flannel-cfg\") pod \"kube-flannel-ds-4mqt9\" (UID: \"f2e6c42c-517b-4a28-8fbf-c92bac82be4c\") " pod="kube-flannel/kube-flannel-ds-4mqt9" Oct 29 23:30:28.401771 kubelet[1848]: I1029 23:30:28.401749 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f2e6c42c-517b-4a28-8fbf-c92bac82be4c-xtables-lock\") pod \"kube-flannel-ds-4mqt9\" (UID: \"f2e6c42c-517b-4a28-8fbf-c92bac82be4c\") " pod="kube-flannel/kube-flannel-ds-4mqt9" Oct 29 23:30:28.401771 kubelet[1848]: I1029 23:30:28.401766 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kn5j4\" (UniqueName: \"kubernetes.io/projected/f2e6c42c-517b-4a28-8fbf-c92bac82be4c-kube-api-access-kn5j4\") pod \"kube-flannel-ds-4mqt9\" (UID: \"f2e6c42c-517b-4a28-8fbf-c92bac82be4c\") " pod="kube-flannel/kube-flannel-ds-4mqt9" Oct 29 23:30:28.401827 kubelet[1848]: I1029 23:30:28.401782 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/cd1accd1-e72d-4089-8851-927c157e026c-kube-proxy\") pod \"kube-proxy-6sffm\" (UID: \"cd1accd1-e72d-4089-8851-927c157e026c\") " pod="kube-system/kube-proxy-6sffm" Oct 29 23:30:28.411100 systemd[1]: Created slice kubepods-burstable-podf2e6c42c_517b_4a28_8fbf_c92bac82be4c.slice - libcontainer container kubepods-burstable-podf2e6c42c_517b_4a28_8fbf_c92bac82be4c.slice. Oct 29 23:30:28.707587 kubelet[1848]: E1029 23:30:28.707470 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:28.708355 containerd[1556]: time="2025-10-29T23:30:28.708310522Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-6sffm,Uid:cd1accd1-e72d-4089-8851-927c157e026c,Namespace:kube-system,Attempt:0,}" Oct 29 23:30:28.722527 kubelet[1848]: E1029 23:30:28.722269 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:28.722798 containerd[1556]: time="2025-10-29T23:30:28.722761318Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4mqt9,Uid:f2e6c42c-517b-4a28-8fbf-c92bac82be4c,Namespace:kube-flannel,Attempt:0,}" Oct 29 23:30:29.244754 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4099483858.mount: Deactivated successfully. Oct 29 23:30:29.250933 containerd[1556]: time="2025-10-29T23:30:29.250887440Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 29 23:30:29.252347 containerd[1556]: time="2025-10-29T23:30:29.252315343Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Oct 29 23:30:29.254748 containerd[1556]: time="2025-10-29T23:30:29.254714846Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 29 23:30:29.256168 containerd[1556]: time="2025-10-29T23:30:29.256110130Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 29 23:30:29.257966 containerd[1556]: time="2025-10-29T23:30:29.257932540Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 29 23:30:29.259044 containerd[1556]: time="2025-10-29T23:30:29.259002597Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 29 23:30:29.259564 containerd[1556]: time="2025-10-29T23:30:29.259497722Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 531.213239ms" Oct 29 23:30:29.264141 containerd[1556]: time="2025-10-29T23:30:29.264109860Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 548.670058ms" Oct 29 23:30:29.279210 containerd[1556]: time="2025-10-29T23:30:29.278839589Z" level=info msg="connecting to shim d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add" address="unix:///run/containerd/s/e482cec0fc25ff3f4852565ae9522be392f186f0b6c0447b2919e29e7ebecb6e" namespace=k8s.io protocol=ttrpc version=3 Oct 29 23:30:29.282807 containerd[1556]: time="2025-10-29T23:30:29.282766332Z" level=info msg="connecting to shim eb7b5756131f9c0687c2304b0b7f6cf09fec0452cf13f1c89a58d0433a064b0f" address="unix:///run/containerd/s/58c3a8d6a3089097c317532ee9f65222a89657e828999c2a7b52be1baaa640ee" namespace=k8s.io protocol=ttrpc version=3 Oct 29 23:30:29.302394 systemd[1]: Started cri-containerd-eb7b5756131f9c0687c2304b0b7f6cf09fec0452cf13f1c89a58d0433a064b0f.scope - libcontainer container eb7b5756131f9c0687c2304b0b7f6cf09fec0452cf13f1c89a58d0433a064b0f. Oct 29 23:30:29.306087 systemd[1]: Started cri-containerd-d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add.scope - libcontainer container d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add. Oct 29 23:30:29.329142 containerd[1556]: time="2025-10-29T23:30:29.329094750Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-6sffm,Uid:cd1accd1-e72d-4089-8851-927c157e026c,Namespace:kube-system,Attempt:0,} returns sandbox id \"eb7b5756131f9c0687c2304b0b7f6cf09fec0452cf13f1c89a58d0433a064b0f\"" Oct 29 23:30:29.333223 kubelet[1848]: E1029 23:30:29.333197 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:29.336515 containerd[1556]: time="2025-10-29T23:30:29.336483848Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\"" Oct 29 23:30:29.351914 containerd[1556]: time="2025-10-29T23:30:29.351866273Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4mqt9,Uid:f2e6c42c-517b-4a28-8fbf-c92bac82be4c,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add\"" Oct 29 23:30:29.352535 kubelet[1848]: E1029 23:30:29.352515 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:29.382729 kubelet[1848]: E1029 23:30:29.382676 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:30.342307 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2759206031.mount: Deactivated successfully. Oct 29 23:30:30.382919 kubelet[1848]: E1029 23:30:30.382876 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:30.589864 containerd[1556]: time="2025-10-29T23:30:30.589803994Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:30.590900 containerd[1556]: time="2025-10-29T23:30:30.590878033Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.9: active requests=0, bytes read=27417819" Oct 29 23:30:30.591880 containerd[1556]: time="2025-10-29T23:30:30.591859781Z" level=info msg="ImageCreate event name:\"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:30.593840 containerd[1556]: time="2025-10-29T23:30:30.593731026Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:30.594645 containerd[1556]: time="2025-10-29T23:30:30.594344529Z" 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 1.257826821s" Oct 29 23:30:30.594645 containerd[1556]: time="2025-10-29T23:30:30.594378467Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\" returns image reference \"sha256:72b57ec14d31e8422925ef4c3eff44822cdc04a11fd30d13824f1897d83a16d4\"" Oct 29 23:30:30.595977 containerd[1556]: time="2025-10-29T23:30:30.595946783Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Oct 29 23:30:30.596761 containerd[1556]: time="2025-10-29T23:30:30.596732422Z" level=info msg="CreateContainer within sandbox \"eb7b5756131f9c0687c2304b0b7f6cf09fec0452cf13f1c89a58d0433a064b0f\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 29 23:30:30.608723 containerd[1556]: time="2025-10-29T23:30:30.608662522Z" level=info msg="Container 212972d8aa1992556e772eaa390f7ba2b122f3267168064985879fc8ec227380: CDI devices from CRI Config.CDIDevices: []" Oct 29 23:30:30.618209 containerd[1556]: time="2025-10-29T23:30:30.618124605Z" level=info msg="CreateContainer within sandbox \"eb7b5756131f9c0687c2304b0b7f6cf09fec0452cf13f1c89a58d0433a064b0f\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"212972d8aa1992556e772eaa390f7ba2b122f3267168064985879fc8ec227380\"" Oct 29 23:30:30.618799 containerd[1556]: time="2025-10-29T23:30:30.618772726Z" level=info msg="StartContainer for \"212972d8aa1992556e772eaa390f7ba2b122f3267168064985879fc8ec227380\"" Oct 29 23:30:30.620222 containerd[1556]: time="2025-10-29T23:30:30.620179832Z" level=info msg="connecting to shim 212972d8aa1992556e772eaa390f7ba2b122f3267168064985879fc8ec227380" address="unix:///run/containerd/s/58c3a8d6a3089097c317532ee9f65222a89657e828999c2a7b52be1baaa640ee" protocol=ttrpc version=3 Oct 29 23:30:30.641355 systemd[1]: Started cri-containerd-212972d8aa1992556e772eaa390f7ba2b122f3267168064985879fc8ec227380.scope - libcontainer container 212972d8aa1992556e772eaa390f7ba2b122f3267168064985879fc8ec227380. Oct 29 23:30:30.674570 containerd[1556]: time="2025-10-29T23:30:30.674530055Z" level=info msg="StartContainer for \"212972d8aa1992556e772eaa390f7ba2b122f3267168064985879fc8ec227380\" returns successfully" Oct 29 23:30:31.383053 kubelet[1848]: E1029 23:30:31.383017 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:31.534154 kubelet[1848]: E1029 23:30:31.534118 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:31.686163 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount547576589.mount: Deactivated successfully. Oct 29 23:30:31.715669 containerd[1556]: time="2025-10-29T23:30:31.715610718Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:31.716246 containerd[1556]: time="2025-10-29T23:30:31.716217766Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Oct 29 23:30:31.717309 containerd[1556]: time="2025-10-29T23:30:31.717258889Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:31.719424 containerd[1556]: time="2025-10-29T23:30:31.719166761Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:31.720169 containerd[1556]: time="2025-10-29T23:30:31.720120877Z" 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.124142237s" Oct 29 23:30:31.720169 containerd[1556]: time="2025-10-29T23:30:31.720157537Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Oct 29 23:30:31.722205 containerd[1556]: time="2025-10-29T23:30:31.721890114Z" level=info msg="CreateContainer within sandbox \"d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 29 23:30:31.731707 containerd[1556]: time="2025-10-29T23:30:31.731641228Z" level=info msg="Container 776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3: CDI devices from CRI Config.CDIDevices: []" Oct 29 23:30:31.733225 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount387015257.mount: Deactivated successfully. Oct 29 23:30:31.740233 containerd[1556]: time="2025-10-29T23:30:31.740160436Z" level=info msg="CreateContainer within sandbox \"d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3\"" Oct 29 23:30:31.740824 containerd[1556]: time="2025-10-29T23:30:31.740789456Z" level=info msg="StartContainer for \"776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3\"" Oct 29 23:30:31.741709 containerd[1556]: time="2025-10-29T23:30:31.741681658Z" level=info msg="connecting to shim 776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3" address="unix:///run/containerd/s/e482cec0fc25ff3f4852565ae9522be392f186f0b6c0447b2919e29e7ebecb6e" protocol=ttrpc version=3 Oct 29 23:30:31.771431 systemd[1]: Started cri-containerd-776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3.scope - libcontainer container 776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3. Oct 29 23:30:31.797076 systemd[1]: cri-containerd-776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3.scope: Deactivated successfully. Oct 29 23:30:31.799026 containerd[1556]: time="2025-10-29T23:30:31.798988252Z" level=info msg="received exit event container_id:\"776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3\" id:\"776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3\" pid:2183 exited_at:{seconds:1761780631 nanos:798325974}" Oct 29 23:30:31.799379 containerd[1556]: time="2025-10-29T23:30:31.799230423Z" level=info msg="TaskExit event in podsandbox handler container_id:\"776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3\" id:\"776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3\" pid:2183 exited_at:{seconds:1761780631 nanos:798325974}" Oct 29 23:30:31.799973 containerd[1556]: time="2025-10-29T23:30:31.799855481Z" level=info msg="StartContainer for \"776cbb56020c5a96b7f86e17008a57365f18bfd20aba1f31daaf483b703dfad3\" returns successfully" Oct 29 23:30:32.384021 kubelet[1848]: E1029 23:30:32.383986 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:32.538821 kubelet[1848]: E1029 23:30:32.538790 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:32.539363 kubelet[1848]: E1029 23:30:32.539339 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:32.540215 containerd[1556]: time="2025-10-29T23:30:32.540168439Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Oct 29 23:30:32.549917 kubelet[1848]: I1029 23:30:32.549854 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-6sffm" podStartSLOduration=5.289507777 podStartE2EDuration="6.549836865s" podCreationTimestamp="2025-10-29 23:30:26 +0000 UTC" firstStartedPulling="2025-10-29 23:30:29.33489137 +0000 UTC m=+4.041300462" lastFinishedPulling="2025-10-29 23:30:30.595220458 +0000 UTC m=+5.301629550" observedRunningTime="2025-10-29 23:30:31.543731518 +0000 UTC m=+6.250140610" watchObservedRunningTime="2025-10-29 23:30:32.549836865 +0000 UTC m=+7.256245957" Oct 29 23:30:33.384793 kubelet[1848]: E1029 23:30:33.384753 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:33.754277 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3372092289.mount: Deactivated successfully. Oct 29 23:30:34.308145 containerd[1556]: time="2025-10-29T23:30:34.308098657Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:34.309055 containerd[1556]: time="2025-10-29T23:30:34.308616992Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Oct 29 23:30:34.309566 containerd[1556]: time="2025-10-29T23:30:34.309517675Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:34.312261 containerd[1556]: time="2025-10-29T23:30:34.312212640Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:34.313583 containerd[1556]: time="2025-10-29T23:30:34.313543094Z" 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.773323429s" Oct 29 23:30:34.313629 containerd[1556]: time="2025-10-29T23:30:34.313581033Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Oct 29 23:30:34.316047 containerd[1556]: time="2025-10-29T23:30:34.315988537Z" level=info msg="CreateContainer within sandbox \"d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 29 23:30:34.324271 containerd[1556]: time="2025-10-29T23:30:34.324232191Z" level=info msg="Container 6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420: CDI devices from CRI Config.CDIDevices: []" Oct 29 23:30:34.331090 containerd[1556]: time="2025-10-29T23:30:34.330961780Z" level=info msg="CreateContainer within sandbox \"d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420\"" Oct 29 23:30:34.331443 containerd[1556]: time="2025-10-29T23:30:34.331418845Z" level=info msg="StartContainer for \"6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420\"" Oct 29 23:30:34.332218 containerd[1556]: time="2025-10-29T23:30:34.332174656Z" level=info msg="connecting to shim 6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420" address="unix:///run/containerd/s/e482cec0fc25ff3f4852565ae9522be392f186f0b6c0447b2919e29e7ebecb6e" protocol=ttrpc version=3 Oct 29 23:30:34.351373 systemd[1]: Started cri-containerd-6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420.scope - libcontainer container 6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420. Oct 29 23:30:34.378560 systemd[1]: cri-containerd-6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420.scope: Deactivated successfully. Oct 29 23:30:34.380039 containerd[1556]: time="2025-10-29T23:30:34.380004256Z" level=info msg="TaskExit event in podsandbox handler container_id:\"6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420\" id:\"6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420\" pid:2257 exited_at:{seconds:1761780634 nanos:379458908}" Oct 29 23:30:34.381160 containerd[1556]: time="2025-10-29T23:30:34.380718247Z" level=info msg="received exit event container_id:\"6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420\" id:\"6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420\" pid:2257 exited_at:{seconds:1761780634 nanos:379458908}" Oct 29 23:30:34.382606 containerd[1556]: time="2025-10-29T23:30:34.382570598Z" level=info msg="StartContainer for \"6472ccdfb92f19f6d1e9d293c2673e3317bf93282497c5e73584907fcf1e1420\" returns successfully" Oct 29 23:30:34.385453 kubelet[1848]: E1029 23:30:34.385419 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:34.402696 kubelet[1848]: I1029 23:30:34.402666 1848 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 29 23:30:34.543154 kubelet[1848]: E1029 23:30:34.543125 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:34.544827 containerd[1556]: time="2025-10-29T23:30:34.544790767Z" level=info msg="CreateContainer within sandbox \"d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 29 23:30:34.554233 containerd[1556]: time="2025-10-29T23:30:34.553707231Z" level=info msg="Container 9c123c802afc9fa1fbbd8e6bc6b70dc9d1d0ee04faef4301beb3f1beeadaa5cd: CDI devices from CRI Config.CDIDevices: []" Oct 29 23:30:34.560938 containerd[1556]: time="2025-10-29T23:30:34.560762341Z" level=info msg="CreateContainer within sandbox \"d3bda7a477094e88fcb21a92b0649d0aab4608630bc196b74171990e36f82add\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"9c123c802afc9fa1fbbd8e6bc6b70dc9d1d0ee04faef4301beb3f1beeadaa5cd\"" Oct 29 23:30:34.561710 containerd[1556]: time="2025-10-29T23:30:34.561551209Z" level=info msg="StartContainer for \"9c123c802afc9fa1fbbd8e6bc6b70dc9d1d0ee04faef4301beb3f1beeadaa5cd\"" Oct 29 23:30:34.562618 containerd[1556]: time="2025-10-29T23:30:34.562595842Z" level=info msg="connecting to shim 9c123c802afc9fa1fbbd8e6bc6b70dc9d1d0ee04faef4301beb3f1beeadaa5cd" address="unix:///run/containerd/s/e482cec0fc25ff3f4852565ae9522be392f186f0b6c0447b2919e29e7ebecb6e" protocol=ttrpc version=3 Oct 29 23:30:34.588410 systemd[1]: Started cri-containerd-9c123c802afc9fa1fbbd8e6bc6b70dc9d1d0ee04faef4301beb3f1beeadaa5cd.scope - libcontainer container 9c123c802afc9fa1fbbd8e6bc6b70dc9d1d0ee04faef4301beb3f1beeadaa5cd. Oct 29 23:30:34.616362 containerd[1556]: time="2025-10-29T23:30:34.616326263Z" level=info msg="StartContainer for \"9c123c802afc9fa1fbbd8e6bc6b70dc9d1d0ee04faef4301beb3f1beeadaa5cd\" returns successfully" Oct 29 23:30:35.386506 kubelet[1848]: E1029 23:30:35.386458 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:35.547641 kubelet[1848]: E1029 23:30:35.547406 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:35.557153 kubelet[1848]: I1029 23:30:35.557091 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-4mqt9" podStartSLOduration=4.5955026740000005 podStartE2EDuration="9.557071706s" podCreationTimestamp="2025-10-29 23:30:26 +0000 UTC" firstStartedPulling="2025-10-29 23:30:29.353011813 +0000 UTC m=+4.059420905" lastFinishedPulling="2025-10-29 23:30:34.314580845 +0000 UTC m=+9.020989937" observedRunningTime="2025-10-29 23:30:35.557050736 +0000 UTC m=+10.263459828" watchObservedRunningTime="2025-10-29 23:30:35.557071706 +0000 UTC m=+10.263480758" Oct 29 23:30:35.668495 systemd-networkd[1447]: flannel.1: Link UP Oct 29 23:30:35.668502 systemd-networkd[1447]: flannel.1: Gained carrier Oct 29 23:30:36.387369 kubelet[1848]: E1029 23:30:36.387316 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:36.549212 kubelet[1848]: E1029 23:30:36.549170 1848 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 29 23:30:37.247390 systemd-networkd[1447]: flannel.1: Gained IPv6LL Oct 29 23:30:37.388475 kubelet[1848]: E1029 23:30:37.388423 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:37.730876 systemd[1]: Created slice kubepods-besteffort-podd2f544a7_ea79_4673_949f_cd089a1f0df5.slice - libcontainer container kubepods-besteffort-podd2f544a7_ea79_4673_949f_cd089a1f0df5.slice. Oct 29 23:30:37.760809 kubelet[1848]: I1029 23:30:37.760767 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cl2t7\" (UniqueName: \"kubernetes.io/projected/d2f544a7-ea79-4673-949f-cd089a1f0df5-kube-api-access-cl2t7\") pod \"nginx-deployment-7fcdb87857-n7hdd\" (UID: \"d2f544a7-ea79-4673-949f-cd089a1f0df5\") " pod="default/nginx-deployment-7fcdb87857-n7hdd" Oct 29 23:30:38.035930 containerd[1556]: time="2025-10-29T23:30:38.035817079Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-n7hdd,Uid:d2f544a7-ea79-4673-949f-cd089a1f0df5,Namespace:default,Attempt:0,}" Oct 29 23:30:38.052290 systemd-networkd[1447]: cni0: Link UP Oct 29 23:30:38.052301 systemd-networkd[1447]: cni0: Gained carrier Oct 29 23:30:38.053462 systemd-networkd[1447]: cni0: Lost carrier Oct 29 23:30:38.067712 kernel: cni0: port 1(veth4ca866c4) entered blocking state Oct 29 23:30:38.067816 kernel: cni0: port 1(veth4ca866c4) entered disabled state Oct 29 23:30:38.068539 kernel: veth4ca866c4: entered allmulticast mode Oct 29 23:30:38.069337 kernel: veth4ca866c4: entered promiscuous mode Oct 29 23:30:38.069664 systemd-networkd[1447]: veth4ca866c4: Link UP Oct 29 23:30:38.082238 kernel: cni0: port 1(veth4ca866c4) entered blocking state Oct 29 23:30:38.082324 kernel: cni0: port 1(veth4ca866c4) entered forwarding state Oct 29 23:30:38.082178 systemd-networkd[1447]: veth4ca866c4: Gained carrier Oct 29 23:30:38.082467 systemd-networkd[1447]: cni0: Gained carrier Oct 29 23:30:38.084829 containerd[1556]: 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"} Oct 29 23:30:38.084829 containerd[1556]: delegateAdd: netconf sent to delegate plugin: Oct 29 23:30:38.118036 containerd[1556]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-29T23:30:38.117803709Z" level=info msg="connecting to shim ff91267a18ba694290b21fd94479dced3f68b7fc6610d1ed94250651c341b9d6" address="unix:///run/containerd/s/f2cdb8769389e2fff07770271b974ea0a3c979c4172ac3ba34d35fd216335dc8" namespace=k8s.io protocol=ttrpc version=3 Oct 29 23:30:38.148430 systemd[1]: Started cri-containerd-ff91267a18ba694290b21fd94479dced3f68b7fc6610d1ed94250651c341b9d6.scope - libcontainer container ff91267a18ba694290b21fd94479dced3f68b7fc6610d1ed94250651c341b9d6. Oct 29 23:30:38.164634 systemd-resolved[1278]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 29 23:30:38.186046 containerd[1556]: time="2025-10-29T23:30:38.186002486Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-n7hdd,Uid:d2f544a7-ea79-4673-949f-cd089a1f0df5,Namespace:default,Attempt:0,} returns sandbox id \"ff91267a18ba694290b21fd94479dced3f68b7fc6610d1ed94250651c341b9d6\"" Oct 29 23:30:38.187673 containerd[1556]: time="2025-10-29T23:30:38.187645838Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 29 23:30:38.389388 kubelet[1848]: E1029 23:30:38.389327 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:39.390129 kubelet[1848]: E1029 23:30:39.390091 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:39.487412 systemd-networkd[1447]: cni0: Gained IPv6LL Oct 29 23:30:39.871350 systemd-networkd[1447]: veth4ca866c4: Gained IPv6LL Oct 29 23:30:39.887336 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3120558836.mount: Deactivated successfully. Oct 29 23:30:40.390862 kubelet[1848]: E1029 23:30:40.390823 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:40.582870 containerd[1556]: time="2025-10-29T23:30:40.582806629Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:40.583975 containerd[1556]: time="2025-10-29T23:30:40.583799472Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Oct 29 23:30:40.584614 containerd[1556]: time="2025-10-29T23:30:40.584584191Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:40.587477 containerd[1556]: time="2025-10-29T23:30:40.587428828Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:40.588751 containerd[1556]: time="2025-10-29T23:30:40.588706667Z" 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.400902841s" Oct 29 23:30:40.588791 containerd[1556]: time="2025-10-29T23:30:40.588748084Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 29 23:30:40.590360 containerd[1556]: time="2025-10-29T23:30:40.590330447Z" level=info msg="CreateContainer within sandbox \"ff91267a18ba694290b21fd94479dced3f68b7fc6610d1ed94250651c341b9d6\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 29 23:30:40.600097 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount674774941.mount: Deactivated successfully. Oct 29 23:30:40.601327 containerd[1556]: time="2025-10-29T23:30:40.601292223Z" level=info msg="Container bdd9ab233c62145c6e92713d6770ed446be0160485487c437ebbbc901cae1e1c: CDI devices from CRI Config.CDIDevices: []" Oct 29 23:30:40.606159 containerd[1556]: time="2025-10-29T23:30:40.606119145Z" level=info msg="CreateContainer within sandbox \"ff91267a18ba694290b21fd94479dced3f68b7fc6610d1ed94250651c341b9d6\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"bdd9ab233c62145c6e92713d6770ed446be0160485487c437ebbbc901cae1e1c\"" Oct 29 23:30:40.607147 containerd[1556]: time="2025-10-29T23:30:40.607119791Z" level=info msg="StartContainer for \"bdd9ab233c62145c6e92713d6770ed446be0160485487c437ebbbc901cae1e1c\"" Oct 29 23:30:40.608265 containerd[1556]: time="2025-10-29T23:30:40.608215477Z" level=info msg="connecting to shim bdd9ab233c62145c6e92713d6770ed446be0160485487c437ebbbc901cae1e1c" address="unix:///run/containerd/s/f2cdb8769389e2fff07770271b974ea0a3c979c4172ac3ba34d35fd216335dc8" protocol=ttrpc version=3 Oct 29 23:30:40.627396 systemd[1]: Started cri-containerd-bdd9ab233c62145c6e92713d6770ed446be0160485487c437ebbbc901cae1e1c.scope - libcontainer container bdd9ab233c62145c6e92713d6770ed446be0160485487c437ebbbc901cae1e1c. Oct 29 23:30:40.654020 containerd[1556]: time="2025-10-29T23:30:40.653827497Z" level=info msg="StartContainer for \"bdd9ab233c62145c6e92713d6770ed446be0160485487c437ebbbc901cae1e1c\" returns successfully" Oct 29 23:30:41.391720 kubelet[1848]: E1029 23:30:41.391672 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:41.572718 kubelet[1848]: I1029 23:30:41.572587 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-n7hdd" podStartSLOduration=2.170379215 podStartE2EDuration="4.572571676s" podCreationTimestamp="2025-10-29 23:30:37 +0000 UTC" firstStartedPulling="2025-10-29 23:30:38.187209929 +0000 UTC m=+12.893619021" lastFinishedPulling="2025-10-29 23:30:40.58940239 +0000 UTC m=+15.295811482" observedRunningTime="2025-10-29 23:30:41.572457671 +0000 UTC m=+16.278866763" watchObservedRunningTime="2025-10-29 23:30:41.572571676 +0000 UTC m=+16.278980768" Oct 29 23:30:42.392674 kubelet[1848]: E1029 23:30:42.392627 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:43.393023 kubelet[1848]: E1029 23:30:43.392967 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:44.171408 systemd[1]: Created slice kubepods-besteffort-poddad7876e_a211_4db5_a290_087ed212c325.slice - libcontainer container kubepods-besteffort-poddad7876e_a211_4db5_a290_087ed212c325.slice. Oct 29 23:30:44.197765 kubelet[1848]: I1029 23:30:44.197702 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-px25p\" (UniqueName: \"kubernetes.io/projected/dad7876e-a211-4db5-a290-087ed212c325-kube-api-access-px25p\") pod \"nfs-server-provisioner-0\" (UID: \"dad7876e-a211-4db5-a290-087ed212c325\") " pod="default/nfs-server-provisioner-0" Oct 29 23:30:44.197765 kubelet[1848]: I1029 23:30:44.197748 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/dad7876e-a211-4db5-a290-087ed212c325-data\") pod \"nfs-server-provisioner-0\" (UID: \"dad7876e-a211-4db5-a290-087ed212c325\") " pod="default/nfs-server-provisioner-0" Oct 29 23:30:44.393967 kubelet[1848]: E1029 23:30:44.393922 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:44.475148 containerd[1556]: time="2025-10-29T23:30:44.475043835Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:dad7876e-a211-4db5-a290-087ed212c325,Namespace:default,Attempt:0,}" Oct 29 23:30:44.485639 systemd-networkd[1447]: vethabfba36b: Link UP Oct 29 23:30:44.488652 kernel: cni0: port 2(vethabfba36b) entered blocking state Oct 29 23:30:44.488700 kernel: cni0: port 2(vethabfba36b) entered disabled state Oct 29 23:30:44.488736 kernel: vethabfba36b: entered allmulticast mode Oct 29 23:30:44.488753 kernel: vethabfba36b: entered promiscuous mode Oct 29 23:30:44.497297 kernel: cni0: port 2(vethabfba36b) entered blocking state Oct 29 23:30:44.497375 kernel: cni0: port 2(vethabfba36b) entered forwarding state Oct 29 23:30:44.497266 systemd-networkd[1447]: vethabfba36b: Gained carrier Oct 29 23:30:44.499387 containerd[1556]: 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"} Oct 29 23:30:44.499387 containerd[1556]: delegateAdd: netconf sent to delegate plugin: Oct 29 23:30:44.525814 containerd[1556]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-29T23:30:44.525760473Z" level=info msg="connecting to shim 7d0a4d8a99b29da675f9e41e1758ecdb9e843b604c121b8631e5507668756905" address="unix:///run/containerd/s/f5603ed150fbb1d176eed39a9e347ee0ea10b445b4874b465695318ad75989a4" namespace=k8s.io protocol=ttrpc version=3 Oct 29 23:30:44.543369 systemd[1]: Started cri-containerd-7d0a4d8a99b29da675f9e41e1758ecdb9e843b604c121b8631e5507668756905.scope - libcontainer container 7d0a4d8a99b29da675f9e41e1758ecdb9e843b604c121b8631e5507668756905. Oct 29 23:30:44.553643 systemd-resolved[1278]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 29 23:30:44.574113 containerd[1556]: time="2025-10-29T23:30:44.574011308Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:dad7876e-a211-4db5-a290-087ed212c325,Namespace:default,Attempt:0,} returns sandbox id \"7d0a4d8a99b29da675f9e41e1758ecdb9e843b604c121b8631e5507668756905\"" Oct 29 23:30:44.575801 containerd[1556]: time="2025-10-29T23:30:44.575590713Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 29 23:30:45.395075 kubelet[1848]: E1029 23:30:45.395013 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:45.887427 systemd-networkd[1447]: vethabfba36b: Gained IPv6LL Oct 29 23:30:46.340054 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount762222471.mount: Deactivated successfully. Oct 29 23:30:46.381842 kubelet[1848]: E1029 23:30:46.381796 1848 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:46.395679 kubelet[1848]: E1029 23:30:46.395626 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:47.396668 kubelet[1848]: E1029 23:30:47.396612 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:47.682964 containerd[1556]: time="2025-10-29T23:30:47.682853803Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:47.683806 containerd[1556]: time="2025-10-29T23:30:47.683757337Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Oct 29 23:30:47.684916 containerd[1556]: time="2025-10-29T23:30:47.684565680Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:47.687654 containerd[1556]: time="2025-10-29T23:30:47.687628157Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:47.689200 containerd[1556]: time="2025-10-29T23:30:47.689088713Z" 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.113461066s" Oct 29 23:30:47.689200 containerd[1556]: time="2025-10-29T23:30:47.689121283Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 29 23:30:47.691109 containerd[1556]: time="2025-10-29T23:30:47.691068397Z" level=info msg="CreateContainer within sandbox \"7d0a4d8a99b29da675f9e41e1758ecdb9e843b604c121b8631e5507668756905\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 29 23:30:47.699004 containerd[1556]: time="2025-10-29T23:30:47.698957205Z" level=info msg="Container 3f7899c56e7b259a2d32ec05f7034fea14d8ef206d491a5f32cf3f5936b26874: CDI devices from CRI Config.CDIDevices: []" Oct 29 23:30:47.701021 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3570881443.mount: Deactivated successfully. Oct 29 23:30:47.705867 containerd[1556]: time="2025-10-29T23:30:47.705816277Z" level=info msg="CreateContainer within sandbox \"7d0a4d8a99b29da675f9e41e1758ecdb9e843b604c121b8631e5507668756905\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"3f7899c56e7b259a2d32ec05f7034fea14d8ef206d491a5f32cf3f5936b26874\"" Oct 29 23:30:47.706332 containerd[1556]: time="2025-10-29T23:30:47.706306757Z" level=info msg="StartContainer for \"3f7899c56e7b259a2d32ec05f7034fea14d8ef206d491a5f32cf3f5936b26874\"" Oct 29 23:30:47.707241 containerd[1556]: time="2025-10-29T23:30:47.707179801Z" level=info msg="connecting to shim 3f7899c56e7b259a2d32ec05f7034fea14d8ef206d491a5f32cf3f5936b26874" address="unix:///run/containerd/s/f5603ed150fbb1d176eed39a9e347ee0ea10b445b4874b465695318ad75989a4" protocol=ttrpc version=3 Oct 29 23:30:47.729376 systemd[1]: Started cri-containerd-3f7899c56e7b259a2d32ec05f7034fea14d8ef206d491a5f32cf3f5936b26874.scope - libcontainer container 3f7899c56e7b259a2d32ec05f7034fea14d8ef206d491a5f32cf3f5936b26874. Oct 29 23:30:47.755209 containerd[1556]: time="2025-10-29T23:30:47.754102195Z" level=info msg="StartContainer for \"3f7899c56e7b259a2d32ec05f7034fea14d8ef206d491a5f32cf3f5936b26874\" returns successfully" Oct 29 23:30:48.397323 kubelet[1848]: E1029 23:30:48.397241 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:48.592697 kubelet[1848]: I1029 23:30:48.592617 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.4781132750000001 podStartE2EDuration="4.592601125s" podCreationTimestamp="2025-10-29 23:30:44 +0000 UTC" firstStartedPulling="2025-10-29 23:30:44.575356789 +0000 UTC m=+19.281765841" lastFinishedPulling="2025-10-29 23:30:47.689844599 +0000 UTC m=+22.396253691" observedRunningTime="2025-10-29 23:30:48.592573476 +0000 UTC m=+23.298982568" watchObservedRunningTime="2025-10-29 23:30:48.592601125 +0000 UTC m=+23.299010217" Oct 29 23:30:49.397532 kubelet[1848]: E1029 23:30:49.397481 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:50.398043 kubelet[1848]: E1029 23:30:50.397963 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:51.398393 kubelet[1848]: E1029 23:30:51.398335 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:52.398893 kubelet[1848]: E1029 23:30:52.398819 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:53.399353 kubelet[1848]: E1029 23:30:53.399267 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:54.399639 kubelet[1848]: E1029 23:30:54.399587 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:55.400665 kubelet[1848]: E1029 23:30:55.400594 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:56.400885 kubelet[1848]: E1029 23:30:56.400848 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:57.401689 kubelet[1848]: E1029 23:30:57.401637 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:57.656093 systemd[1]: Created slice kubepods-besteffort-podc4625b46_73c2_471b_b10c_9412f5766616.slice - libcontainer container kubepods-besteffort-podc4625b46_73c2_471b_b10c_9412f5766616.slice. Oct 29 23:30:57.674632 kubelet[1848]: I1029 23:30:57.674575 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-f07ab4d2-2033-46b3-9b80-4799803a5216\" (UniqueName: \"kubernetes.io/nfs/c4625b46-73c2-471b-b10c-9412f5766616-pvc-f07ab4d2-2033-46b3-9b80-4799803a5216\") pod \"test-pod-1\" (UID: \"c4625b46-73c2-471b-b10c-9412f5766616\") " pod="default/test-pod-1" Oct 29 23:30:57.674632 kubelet[1848]: I1029 23:30:57.674632 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nl6vz\" (UniqueName: \"kubernetes.io/projected/c4625b46-73c2-471b-b10c-9412f5766616-kube-api-access-nl6vz\") pod \"test-pod-1\" (UID: \"c4625b46-73c2-471b-b10c-9412f5766616\") " pod="default/test-pod-1" Oct 29 23:30:57.795212 kernel: netfs: FS-Cache loaded Oct 29 23:30:57.818546 kernel: RPC: Registered named UNIX socket transport module. Oct 29 23:30:57.818608 kernel: RPC: Registered udp transport module. Oct 29 23:30:57.818625 kernel: RPC: Registered tcp transport module. Oct 29 23:30:57.818641 kernel: RPC: Registered tcp-with-tls transport module. Oct 29 23:30:57.819224 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 29 23:30:57.990596 kernel: NFS: Registering the id_resolver key type Oct 29 23:30:57.990746 kernel: Key type id_resolver registered Oct 29 23:30:57.990775 kernel: Key type id_legacy registered Oct 29 23:30:58.008709 nfsidmap[2872]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Oct 29 23:30:58.009463 nfsidmap[2872]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 29 23:30:58.012359 nfsidmap[2875]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Oct 29 23:30:58.012509 nfsidmap[2875]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 29 23:30:58.017740 nfsrahead[2879]: setting /var/lib/kubelet/pods/c4625b46-73c2-471b-b10c-9412f5766616/volumes/kubernetes.io~nfs/pvc-f07ab4d2-2033-46b3-9b80-4799803a5216 readahead to 128 Oct 29 23:30:58.260050 containerd[1556]: time="2025-10-29T23:30:58.259943848Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:c4625b46-73c2-471b-b10c-9412f5766616,Namespace:default,Attempt:0,}" Oct 29 23:30:58.272684 systemd-networkd[1447]: veth1f79ef0c: Link UP Oct 29 23:30:58.275952 kernel: cni0: port 3(veth1f79ef0c) entered blocking state Oct 29 23:30:58.276087 kernel: cni0: port 3(veth1f79ef0c) entered disabled state Oct 29 23:30:58.280338 kernel: veth1f79ef0c: entered allmulticast mode Oct 29 23:30:58.280391 kernel: veth1f79ef0c: entered promiscuous mode Oct 29 23:30:58.286580 kernel: cni0: port 3(veth1f79ef0c) entered blocking state Oct 29 23:30:58.286634 kernel: cni0: port 3(veth1f79ef0c) entered forwarding state Oct 29 23:30:58.286656 systemd-networkd[1447]: veth1f79ef0c: Gained carrier Oct 29 23:30:58.287950 containerd[1556]: 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"} Oct 29 23:30:58.287950 containerd[1556]: delegateAdd: netconf sent to delegate plugin: Oct 29 23:30:58.307982 containerd[1556]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-29T23:30:58.307943987Z" level=info msg="connecting to shim 883607d7fd8d2bf411bb30d0a046eb2879b23408c63bd028ec97d0344c798eb9" address="unix:///run/containerd/s/41e3bc87ceda22a06654327ed081d10fdb0bf959ffe14c4594fdc8f338368d96" namespace=k8s.io protocol=ttrpc version=3 Oct 29 23:30:58.340382 systemd[1]: Started cri-containerd-883607d7fd8d2bf411bb30d0a046eb2879b23408c63bd028ec97d0344c798eb9.scope - libcontainer container 883607d7fd8d2bf411bb30d0a046eb2879b23408c63bd028ec97d0344c798eb9. Oct 29 23:30:58.351816 systemd-resolved[1278]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 29 23:30:58.370069 containerd[1556]: time="2025-10-29T23:30:58.370029921Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:c4625b46-73c2-471b-b10c-9412f5766616,Namespace:default,Attempt:0,} returns sandbox id \"883607d7fd8d2bf411bb30d0a046eb2879b23408c63bd028ec97d0344c798eb9\"" Oct 29 23:30:58.371681 containerd[1556]: time="2025-10-29T23:30:58.371651453Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 29 23:30:58.402113 kubelet[1848]: E1029 23:30:58.402072 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:58.664662 containerd[1556]: time="2025-10-29T23:30:58.664611750Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 29 23:30:58.665831 containerd[1556]: time="2025-10-29T23:30:58.665802623Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 29 23:30:58.668477 containerd[1556]: time="2025-10-29T23:30:58.668446710Z" 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 296.760449ms" Oct 29 23:30:58.668613 containerd[1556]: time="2025-10-29T23:30:58.668481278Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 29 23:30:58.670414 containerd[1556]: time="2025-10-29T23:30:58.670348147Z" level=info msg="CreateContainer within sandbox \"883607d7fd8d2bf411bb30d0a046eb2879b23408c63bd028ec97d0344c798eb9\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 29 23:30:58.680073 containerd[1556]: time="2025-10-29T23:30:58.680026168Z" level=info msg="Container ae9f8de9f7b3072b20b3f1fecc83e1e4346ff61f14ec6b3645d8e11655d67c31: CDI devices from CRI Config.CDIDevices: []" Oct 29 23:30:58.689962 containerd[1556]: time="2025-10-29T23:30:58.689902076Z" level=info msg="CreateContainer within sandbox \"883607d7fd8d2bf411bb30d0a046eb2879b23408c63bd028ec97d0344c798eb9\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"ae9f8de9f7b3072b20b3f1fecc83e1e4346ff61f14ec6b3645d8e11655d67c31\"" Oct 29 23:30:58.690653 containerd[1556]: time="2025-10-29T23:30:58.690613559Z" level=info msg="StartContainer for \"ae9f8de9f7b3072b20b3f1fecc83e1e4346ff61f14ec6b3645d8e11655d67c31\"" Oct 29 23:30:58.691656 containerd[1556]: time="2025-10-29T23:30:58.691618350Z" level=info msg="connecting to shim ae9f8de9f7b3072b20b3f1fecc83e1e4346ff61f14ec6b3645d8e11655d67c31" address="unix:///run/containerd/s/41e3bc87ceda22a06654327ed081d10fdb0bf959ffe14c4594fdc8f338368d96" protocol=ttrpc version=3 Oct 29 23:30:58.709383 systemd[1]: Started cri-containerd-ae9f8de9f7b3072b20b3f1fecc83e1e4346ff61f14ec6b3645d8e11655d67c31.scope - libcontainer container ae9f8de9f7b3072b20b3f1fecc83e1e4346ff61f14ec6b3645d8e11655d67c31. Oct 29 23:30:58.734431 containerd[1556]: time="2025-10-29T23:30:58.734394770Z" level=info msg="StartContainer for \"ae9f8de9f7b3072b20b3f1fecc83e1e4346ff61f14ec6b3645d8e11655d67c31\" returns successfully" Oct 29 23:30:59.402262 kubelet[1848]: E1029 23:30:59.402215 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:30:59.610651 kubelet[1848]: I1029 23:30:59.610580 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.312601376 podStartE2EDuration="15.610563661s" podCreationTimestamp="2025-10-29 23:30:44 +0000 UTC" firstStartedPulling="2025-10-29 23:30:58.371168142 +0000 UTC m=+33.077577194" lastFinishedPulling="2025-10-29 23:30:58.669130387 +0000 UTC m=+33.375539479" observedRunningTime="2025-10-29 23:30:59.610543177 +0000 UTC m=+34.316952229" watchObservedRunningTime="2025-10-29 23:30:59.610563661 +0000 UTC m=+34.316972753" Oct 29 23:31:00.223367 systemd-networkd[1447]: veth1f79ef0c: Gained IPv6LL Oct 29 23:31:00.402973 kubelet[1848]: E1029 23:31:00.402919 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 29 23:31:01.404110 kubelet[1848]: E1029 23:31:01.404042 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"