Oct 21 00:31:13.851463 kernel: Linux version 6.6.159-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Tue Oct 20 21:46:10 -00 2026 Oct 21 00:31:13.851480 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 verity.usrhash=e5ecef46f2b4a9e3a4706a3c1f0c4d71a161869371286766f303abb150208cb2 Oct 21 00:31:13.851489 kernel: BIOS-provided physical RAM map: Oct 21 00:31:13.851511 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 21 00:31:13.851516 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Oct 21 00:31:13.851522 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Oct 21 00:31:13.851528 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Oct 21 00:31:13.851533 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Oct 21 00:31:13.851538 kernel: BIOS-e820: [mem 0x000000000080c000-0x000000000080ffff] usable Oct 21 00:31:13.851543 kernel: BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS Oct 21 00:31:13.851548 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009c8eefff] usable Oct 21 00:31:13.851555 kernel: BIOS-e820: [mem 0x000000009c8ef000-0x000000009c9eefff] reserved Oct 21 00:31:13.851560 kernel: BIOS-e820: [mem 0x000000009c9ef000-0x000000009caeefff] type 20 Oct 21 00:31:13.851565 kernel: BIOS-e820: [mem 0x000000009caef000-0x000000009cb6efff] reserved Oct 21 00:31:13.851572 kernel: BIOS-e820: [mem 0x000000009cb6f000-0x000000009cb7efff] ACPI data Oct 21 00:31:13.851577 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Oct 21 00:31:13.851584 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009cf3ffff] usable Oct 21 00:31:13.851590 kernel: BIOS-e820: [mem 0x000000009cf40000-0x000000009cf5ffff] reserved Oct 21 00:31:13.851595 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Oct 21 00:31:13.851600 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Oct 21 00:31:13.851606 kernel: NX (Execute Disable) protection: active Oct 21 00:31:13.851611 kernel: APIC: Static calls initialized Oct 21 00:31:13.851616 kernel: efi: EFI v2.7 by EDK II Oct 21 00:31:13.851622 kernel: efi: SMBIOS=0x9c9ab000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b674118 Oct 21 00:31:13.851627 kernel: SMBIOS 2.8 present. Oct 21 00:31:13.851633 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 0.0.0 02/06/2015 Oct 21 00:31:13.851638 kernel: Hypervisor detected: KVM Oct 21 00:31:13.851643 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Oct 21 00:31:13.851650 kernel: kvm-clock: using sched offset of 4597453871 cycles Oct 21 00:31:13.851656 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 21 00:31:13.851662 kernel: tsc: Detected 2793.438 MHz processor Oct 21 00:31:13.851668 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 21 00:31:13.851674 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 21 00:31:13.851679 kernel: last_pfn = 0x9cf40 max_arch_pfn = 0x10000000000 Oct 21 00:31:13.851685 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Oct 21 00:31:13.851764 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 21 00:31:13.851770 kernel: Using GB pages for direct mapping Oct 21 00:31:13.851778 kernel: Secure boot disabled Oct 21 00:31:13.851783 kernel: ACPI: Early table checksum verification disabled Oct 21 00:31:13.851789 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Oct 21 00:31:13.851797 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Oct 21 00:31:13.851803 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 21 00:31:13.851809 kernel: ACPI: DSDT 0x000000009CB7A000 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 21 00:31:13.851816 kernel: ACPI: FACS 0x000000009CBDD000 000040 Oct 21 00:31:13.851822 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 21 00:31:13.851828 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 21 00:31:13.851834 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 21 00:31:13.851840 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 21 00:31:13.851846 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Oct 21 00:31:13.851851 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Oct 21 00:31:13.851856 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1b9] Oct 21 00:31:13.851862 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Oct 21 00:31:13.851867 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Oct 21 00:31:13.851871 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Oct 21 00:31:13.851876 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Oct 21 00:31:13.851881 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Oct 21 00:31:13.851886 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Oct 21 00:31:13.851891 kernel: No NUMA configuration found Oct 21 00:31:13.851896 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cf3ffff] Oct 21 00:31:13.851901 kernel: NODE_DATA(0) allocated [mem 0x9cea6000-0x9ceabfff] Oct 21 00:31:13.851907 kernel: Zone ranges: Oct 21 00:31:13.851912 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 21 00:31:13.851917 kernel: DMA32 [mem 0x0000000001000000-0x000000009cf3ffff] Oct 21 00:31:13.851922 kernel: Normal empty Oct 21 00:31:13.851926 kernel: Movable zone start for each node Oct 21 00:31:13.851931 kernel: Early memory node ranges Oct 21 00:31:13.851936 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 21 00:31:13.851941 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Oct 21 00:31:13.851946 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Oct 21 00:31:13.851951 kernel: node 0: [mem 0x000000000080c000-0x000000000080ffff] Oct 21 00:31:13.851957 kernel: node 0: [mem 0x0000000000900000-0x000000009c8eefff] Oct 21 00:31:13.851962 kernel: node 0: [mem 0x000000009cbff000-0x000000009cf3ffff] Oct 21 00:31:13.851967 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cf3ffff] Oct 21 00:31:13.851971 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 21 00:31:13.851976 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 21 00:31:13.851981 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Oct 21 00:31:13.851986 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 21 00:31:13.851991 kernel: On node 0, zone DMA: 240 pages in unavailable ranges Oct 21 00:31:13.851996 kernel: On node 0, zone DMA32: 784 pages in unavailable ranges Oct 21 00:31:13.852002 kernel: On node 0, zone DMA32: 12480 pages in unavailable ranges Oct 21 00:31:13.852007 kernel: ACPI: PM-Timer IO Port: 0x608 Oct 21 00:31:13.852012 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Oct 21 00:31:13.852017 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Oct 21 00:31:13.852022 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Oct 21 00:31:13.852027 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Oct 21 00:31:13.852031 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 21 00:31:13.852036 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Oct 21 00:31:13.852041 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Oct 21 00:31:13.852047 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 21 00:31:13.852052 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Oct 21 00:31:13.852057 kernel: TSC deadline timer available Oct 21 00:31:13.852062 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Oct 21 00:31:13.852067 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Oct 21 00:31:13.852072 kernel: kvm-guest: KVM setup pv remote TLB flush Oct 21 00:31:13.852076 kernel: kvm-guest: setup PV sched yield Oct 21 00:31:13.852081 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices Oct 21 00:31:13.852086 kernel: Booting paravirtualized kernel on KVM Oct 21 00:31:13.852093 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 21 00:31:13.852098 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Oct 21 00:31:13.852103 kernel: percpu: Embedded 57 pages/cpu s196392 r8192 d28888 u524288 Oct 21 00:31:13.852108 kernel: pcpu-alloc: s196392 r8192 d28888 u524288 alloc=1*2097152 Oct 21 00:31:13.852112 kernel: pcpu-alloc: [0] 0 1 2 3 Oct 21 00:31:13.852117 kernel: kvm-guest: PV spinlocks enabled Oct 21 00:31:13.852122 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 21 00:31:13.852128 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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 verity.usrhash=e5ecef46f2b4a9e3a4706a3c1f0c4d71a161869371286766f303abb150208cb2 Oct 21 00:31:13.852133 kernel: random: crng init done Oct 21 00:31:13.852139 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 21 00:31:13.852144 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 21 00:31:13.852149 kernel: Fallback order for Node 0: 0 Oct 21 00:31:13.852154 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629759 Oct 21 00:31:13.852159 kernel: Policy zone: DMA32 Oct 21 00:31:13.852164 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 21 00:31:13.852169 kernel: Memory: 2392628K/2567000K available (14336K kernel code, 2288K rwdata, 22780K rodata, 42936K init, 2216K bss, 174168K reserved, 0K cma-reserved) Oct 21 00:31:13.852174 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 21 00:31:13.852180 kernel: ftrace: allocating 37527 entries in 148 pages Oct 21 00:31:13.852185 kernel: ftrace: allocated 148 pages with 3 groups Oct 21 00:31:13.852190 kernel: Dynamic Preempt: voluntary Oct 21 00:31:13.852195 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 21 00:31:13.852205 kernel: rcu: RCU event tracing is enabled. Oct 21 00:31:13.852213 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 21 00:31:13.852218 kernel: Trampoline variant of Tasks RCU enabled. Oct 21 00:31:13.852224 kernel: Rude variant of Tasks RCU enabled. Oct 21 00:31:13.852229 kernel: Tracing variant of Tasks RCU enabled. Oct 21 00:31:13.852234 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 21 00:31:13.852240 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 21 00:31:13.852245 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Oct 21 00:31:13.852252 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 21 00:31:13.852257 kernel: Console: colour dummy device 80x25 Oct 21 00:31:13.852263 kernel: printk: console [ttyS0] enabled Oct 21 00:31:13.852268 kernel: ACPI: Core revision 20230628 Oct 21 00:31:13.852273 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Oct 21 00:31:13.852280 kernel: APIC: Switch to symmetric I/O mode setup Oct 21 00:31:13.852285 kernel: x2apic enabled Oct 21 00:31:13.852291 kernel: APIC: Switched APIC routing to: physical x2apic Oct 21 00:31:13.852296 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Oct 21 00:31:13.852302 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Oct 21 00:31:13.852307 kernel: kvm-guest: setup PV IPIs Oct 21 00:31:13.852312 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Oct 21 00:31:13.852318 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Oct 21 00:31:13.852323 kernel: Calibrating delay loop (skipped) preset value.. 5586.87 BogoMIPS (lpj=2793438) Oct 21 00:31:13.852330 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Oct 21 00:31:13.852335 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Oct 21 00:31:13.852341 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Oct 21 00:31:13.852346 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 21 00:31:13.852351 kernel: Spectre V2 : Mitigation: Retpolines Oct 21 00:31:13.852357 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 21 00:31:13.852362 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Oct 21 00:31:13.852368 kernel: RETBleed: Vulnerable Oct 21 00:31:13.852373 kernel: Speculative Store Bypass: Vulnerable Oct 21 00:31:13.852380 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 21 00:31:13.852385 kernel: GDS: Unknown: Dependent on hypervisor status Oct 21 00:31:13.852390 kernel: active return thunk: its_return_thunk Oct 21 00:31:13.852396 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 21 00:31:13.852401 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 21 00:31:13.852406 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 21 00:31:13.852412 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 21 00:31:13.852417 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Oct 21 00:31:13.852423 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Oct 21 00:31:13.852429 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Oct 21 00:31:13.852435 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 21 00:31:13.852440 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Oct 21 00:31:13.852445 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Oct 21 00:31:13.852451 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Oct 21 00:31:13.852456 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Oct 21 00:31:13.852461 kernel: Freeing SMP alternatives memory: 32K Oct 21 00:31:13.852467 kernel: pid_max: default: 32768 minimum: 301 Oct 21 00:31:13.852472 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 21 00:31:13.852479 kernel: landlock: Up and running. Oct 21 00:31:13.852484 kernel: SELinux: Initializing. Oct 21 00:31:13.852490 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 21 00:31:13.852508 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 21 00:31:13.852514 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8370C CPU @ 2.80GHz (family: 0x6, model: 0x6a, stepping: 0x6) Oct 21 00:31:13.852519 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 21 00:31:13.852525 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 21 00:31:13.852530 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 21 00:31:13.852537 kernel: Performance Events: unsupported p6 CPU model 106 no PMU driver, software events only. Oct 21 00:31:13.852542 kernel: signal: max sigframe size: 3632 Oct 21 00:31:13.852548 kernel: rcu: Hierarchical SRCU implementation. Oct 21 00:31:13.852553 kernel: rcu: Max phase no-delay instances is 400. Oct 21 00:31:13.852559 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 21 00:31:13.852564 kernel: smp: Bringing up secondary CPUs ... Oct 21 00:31:13.852569 kernel: smpboot: x86: Booting SMP configuration: Oct 21 00:31:13.852575 kernel: .... node #0, CPUs: #1 #2 #3 Oct 21 00:31:13.852580 kernel: smp: Brought up 1 node, 4 CPUs Oct 21 00:31:13.852585 kernel: smpboot: Max logical packages: 1 Oct 21 00:31:13.852592 kernel: smpboot: Total of 4 processors activated (22347.50 BogoMIPS) Oct 21 00:31:13.852597 kernel: devtmpfs: initialized Oct 21 00:31:13.852603 kernel: x86/mm: Memory block size: 128MB Oct 21 00:31:13.852608 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Oct 21 00:31:13.852614 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Oct 21 00:31:13.852619 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) Oct 21 00:31:13.852625 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Oct 21 00:31:13.852630 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Oct 21 00:31:13.852637 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 21 00:31:13.852642 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 21 00:31:13.852648 kernel: pinctrl core: initialized pinctrl subsystem Oct 21 00:31:13.852653 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 21 00:31:13.852658 kernel: audit: initializing netlink subsys (disabled) Oct 21 00:31:13.852664 kernel: audit: type=2000 audit(1792542673.671:1): state=initialized audit_enabled=0 res=1 Oct 21 00:31:13.852669 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 21 00:31:13.852674 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 21 00:31:13.852680 kernel: cpuidle: using governor menu Oct 21 00:31:13.852686 kernel: efi: Freeing EFI boot services memory: 34216K Oct 21 00:31:13.852712 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 21 00:31:13.852717 kernel: dca service started, version 1.12.1 Oct 21 00:31:13.852723 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Oct 21 00:31:13.852728 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Oct 21 00:31:13.852734 kernel: PCI: Using configuration type 1 for base access Oct 21 00:31:13.852739 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 21 00:31:13.852745 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 21 00:31:13.852750 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 21 00:31:13.852757 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 21 00:31:13.852762 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 21 00:31:13.852768 kernel: ACPI: Added _OSI(Module Device) Oct 21 00:31:13.852773 kernel: ACPI: Added _OSI(Processor Device) Oct 21 00:31:13.852778 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 21 00:31:13.852784 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 21 00:31:13.852789 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 21 00:31:13.852794 kernel: ACPI: Interpreter enabled Oct 21 00:31:13.852800 kernel: ACPI: PM: (supports S0 S3 S5) Oct 21 00:31:13.852806 kernel: ACPI: Using IOAPIC for interrupt routing Oct 21 00:31:13.852812 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 21 00:31:13.852817 kernel: PCI: Using E820 reservations for host bridge windows Oct 21 00:31:13.852823 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Oct 21 00:31:13.852828 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 21 00:31:13.852933 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 21 00:31:13.852995 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Oct 21 00:31:13.853050 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Oct 21 00:31:13.853059 kernel: PCI host bridge to bus 0000:00 Oct 21 00:31:13.853119 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 21 00:31:13.853169 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 21 00:31:13.853218 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Oct 21 00:31:13.853267 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Oct 21 00:31:13.853316 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Oct 21 00:31:13.853365 kernel: pci_bus 0000:00: root bus resource [mem 0x800000000-0xfffffffff window] Oct 21 00:31:13.853415 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 21 00:31:13.853484 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Oct 21 00:31:13.853565 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Oct 21 00:31:13.853621 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Oct 21 00:31:13.853675 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Oct 21 00:31:13.853754 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Oct 21 00:31:13.853813 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Oct 21 00:31:13.853869 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 21 00:31:13.853930 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Oct 21 00:31:13.853987 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Oct 21 00:31:13.854041 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Oct 21 00:31:13.854096 kernel: pci 0000:00:02.0: BAR 4 [mem 0x800000000-0x800003fff 64bit pref] Oct 21 00:31:13.854157 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Oct 21 00:31:13.854215 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Oct 21 00:31:13.854271 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Oct 21 00:31:13.854326 kernel: pci 0000:00:03.0: BAR 4 [mem 0x800004000-0x800007fff 64bit pref] Oct 21 00:31:13.854386 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Oct 21 00:31:13.854442 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Oct 21 00:31:13.854512 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Oct 21 00:31:13.854571 kernel: pci 0000:00:04.0: BAR 4 [mem 0x800008000-0x80000bfff 64bit pref] Oct 21 00:31:13.854629 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Oct 21 00:31:13.854707 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Oct 21 00:31:13.854767 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Oct 21 00:31:13.854828 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Oct 21 00:31:13.854886 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Oct 21 00:31:13.854941 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Oct 21 00:31:13.855000 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Oct 21 00:31:13.855057 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Oct 21 00:31:13.855065 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Oct 21 00:31:13.855070 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 21 00:31:13.855075 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Oct 21 00:31:13.855081 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 21 00:31:13.855086 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Oct 21 00:31:13.855092 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Oct 21 00:31:13.855097 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Oct 21 00:31:13.855104 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Oct 21 00:31:13.855109 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Oct 21 00:31:13.855115 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Oct 21 00:31:13.855120 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Oct 21 00:31:13.855126 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Oct 21 00:31:13.855131 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Oct 21 00:31:13.855136 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Oct 21 00:31:13.855142 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Oct 21 00:31:13.855147 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Oct 21 00:31:13.855154 kernel: iommu: Default domain type: Translated Oct 21 00:31:13.855159 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 21 00:31:13.855165 kernel: efivars: Registered efivars operations Oct 21 00:31:13.855170 kernel: PCI: Using ACPI for IRQ routing Oct 21 00:31:13.855176 kernel: PCI: pci_cache_line_size set to 64 bytes Oct 21 00:31:13.855181 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Oct 21 00:31:13.855186 kernel: e820: reserve RAM buffer [mem 0x00810000-0x008fffff] Oct 21 00:31:13.855192 kernel: e820: reserve RAM buffer [mem 0x9c8ef000-0x9fffffff] Oct 21 00:31:13.855197 kernel: e820: reserve RAM buffer [mem 0x9cf40000-0x9fffffff] Oct 21 00:31:13.855252 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Oct 21 00:31:13.855307 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Oct 21 00:31:13.855361 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 21 00:31:13.855368 kernel: vgaarb: loaded Oct 21 00:31:13.855374 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Oct 21 00:31:13.855379 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Oct 21 00:31:13.855384 kernel: clocksource: Switched to clocksource kvm-clock Oct 21 00:31:13.855390 kernel: VFS: Disk quotas dquot_6.6.0 Oct 21 00:31:13.855397 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 21 00:31:13.855402 kernel: pnp: PnP ACPI init Oct 21 00:31:13.855466 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Oct 21 00:31:13.855474 kernel: pnp: PnP ACPI: found 6 devices Oct 21 00:31:13.855479 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 21 00:31:13.855485 kernel: NET: Registered PF_INET protocol family Oct 21 00:31:13.855490 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 21 00:31:13.855510 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 21 00:31:13.855516 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 21 00:31:13.855523 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 21 00:31:13.855529 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 21 00:31:13.855534 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 21 00:31:13.855540 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 21 00:31:13.855545 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 21 00:31:13.855551 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 21 00:31:13.855556 kernel: NET: Registered PF_XDP protocol family Oct 21 00:31:13.855614 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Oct 21 00:31:13.855672 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Oct 21 00:31:13.855747 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 21 00:31:13.855798 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 21 00:31:13.855847 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Oct 21 00:31:13.855896 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Oct 21 00:31:13.855944 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Oct 21 00:31:13.855993 kernel: pci_bus 0000:00: resource 9 [mem 0x800000000-0xfffffffff window] Oct 21 00:31:13.856000 kernel: PCI: CLS 0 bytes, default 64 Oct 21 00:31:13.856008 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 21 00:31:13.856014 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Oct 21 00:31:13.856019 kernel: Initialise system trusted keyrings Oct 21 00:31:13.856029 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 21 00:31:13.856035 kernel: Key type asymmetric registered Oct 21 00:31:13.856040 kernel: Asymmetric key parser 'x509' registered Oct 21 00:31:13.856046 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 21 00:31:13.856058 kernel: io scheduler mq-deadline registered Oct 21 00:31:13.856066 kernel: io scheduler kyber registered Oct 21 00:31:13.856076 kernel: io scheduler bfq registered Oct 21 00:31:13.856085 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 21 00:31:13.856093 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Oct 21 00:31:13.856102 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Oct 21 00:31:13.856111 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Oct 21 00:31:13.856119 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 21 00:31:13.856127 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 21 00:31:13.856135 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Oct 21 00:31:13.856143 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Oct 21 00:31:13.856153 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Oct 21 00:31:13.856233 kernel: rtc_cmos 00:04: RTC can wake from S4 Oct 21 00:31:13.856241 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Oct 21 00:31:13.856293 kernel: rtc_cmos 00:04: registered as rtc0 Oct 21 00:31:13.856344 kernel: rtc_cmos 00:04: setting system clock to 2026-10-21T00:31:13 UTC (1792542673) Oct 21 00:31:13.856395 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Oct 21 00:31:13.856402 kernel: intel_pstate: CPU model not supported Oct 21 00:31:13.856408 kernel: efifb: probing for efifb Oct 21 00:31:13.856415 kernel: efifb: framebuffer at 0xc0000000, using 1408k, total 1408k Oct 21 00:31:13.856421 kernel: efifb: mode is 800x600x24, linelength=2400, pages=1 Oct 21 00:31:13.856426 kernel: efifb: scrolling: redraw Oct 21 00:31:13.856431 kernel: efifb: Truecolor: size=0:8:8:8, shift=0:16:8:0 Oct 21 00:31:13.856437 kernel: Console: switching to colour frame buffer device 100x37 Oct 21 00:31:13.856454 kernel: fb0: EFI VGA frame buffer device Oct 21 00:31:13.856460 kernel: pstore: Using crash dump compression: deflate Oct 21 00:31:13.856466 kernel: pstore: Registered efi_pstore as persistent store backend Oct 21 00:31:13.856472 kernel: NET: Registered PF_INET6 protocol family Oct 21 00:31:13.856479 kernel: Segment Routing with IPv6 Oct 21 00:31:13.856484 kernel: In-situ OAM (IOAM) with IPv6 Oct 21 00:31:13.856490 kernel: NET: Registered PF_PACKET protocol family Oct 21 00:31:13.856543 kernel: Key type dns_resolver registered Oct 21 00:31:13.856551 kernel: IPI shorthand broadcast: enabled Oct 21 00:31:13.856557 kernel: sched_clock: Marking stable (824007363, 196317009)->(1064882004, -44557632) Oct 21 00:31:13.856563 kernel: registered taskstats version 1 Oct 21 00:31:13.856569 kernel: Loading compiled-in X.509 certificates Oct 21 00:31:13.856574 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.159-flatcar: 4703862c98c57d0e31d5fc939e9c6a5908cfcbb4' Oct 21 00:31:13.856582 kernel: Key type .fscrypt registered Oct 21 00:31:13.856588 kernel: Key type fscrypt-provisioning registered Oct 21 00:31:13.856593 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 21 00:31:13.856599 kernel: ima: Allocated hash algorithm: sha1 Oct 21 00:31:13.856604 kernel: ima: No architecture policies found Oct 21 00:31:13.856610 kernel: clk: Disabling unused clocks Oct 21 00:31:13.856615 kernel: Freeing unused kernel image (initmem) memory: 42936K Oct 21 00:31:13.856621 kernel: Write protecting the kernel read-only data: 38912k Oct 21 00:31:13.856627 kernel: Freeing unused kernel image (rodata/data gap) memory: 1796K Oct 21 00:31:13.856634 kernel: Run /init as init process Oct 21 00:31:13.856639 kernel: with arguments: Oct 21 00:31:13.856646 kernel: /init Oct 21 00:31:13.856652 kernel: with environment: Oct 21 00:31:13.856657 kernel: HOME=/ Oct 21 00:31:13.856665 kernel: TERM=linux Oct 21 00:31:13.856673 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 21 00:31:13.856681 systemd[1]: Detected virtualization kvm. Oct 21 00:31:13.856687 systemd[1]: Detected architecture x86-64. Oct 21 00:31:13.856721 systemd[1]: Running in initrd. Oct 21 00:31:13.856726 systemd[1]: No hostname configured, using default hostname. Oct 21 00:31:13.856732 systemd[1]: Hostname set to . Oct 21 00:31:13.856739 systemd[1]: Initializing machine ID from VM UUID. Oct 21 00:31:13.856746 systemd[1]: Queued start job for default target initrd.target. Oct 21 00:31:13.856753 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 21 00:31:13.856759 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 21 00:31:13.856765 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 21 00:31:13.856772 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 21 00:31:13.856778 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 21 00:31:13.856784 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 21 00:31:13.856793 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 21 00:31:13.856799 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 21 00:31:13.856805 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 21 00:31:13.856811 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 21 00:31:13.856817 systemd[1]: Reached target paths.target - Path Units. Oct 21 00:31:13.856823 systemd[1]: Reached target slices.target - Slice Units. Oct 21 00:31:13.856829 systemd[1]: Reached target swap.target - Swaps. Oct 21 00:31:13.856835 systemd[1]: Reached target timers.target - Timer Units. Oct 21 00:31:13.856843 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 21 00:31:13.856849 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 21 00:31:13.856855 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 21 00:31:13.856861 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 21 00:31:13.856867 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 21 00:31:13.856873 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 21 00:31:13.856879 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 21 00:31:13.856885 systemd[1]: Reached target sockets.target - Socket Units. Oct 21 00:31:13.856893 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 21 00:31:13.856900 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 21 00:31:13.856906 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 21 00:31:13.856912 systemd[1]: Starting systemd-fsck-usr.service... Oct 21 00:31:13.856918 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 21 00:31:13.856924 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 21 00:31:13.856930 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 21 00:31:13.856936 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 21 00:31:13.856956 systemd-journald[192]: Collecting audit messages is disabled. Oct 21 00:31:13.856973 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 21 00:31:13.856979 systemd[1]: Finished systemd-fsck-usr.service. Oct 21 00:31:13.856988 systemd-journald[192]: Journal started Oct 21 00:31:13.857003 systemd-journald[192]: Runtime Journal (/run/log/journal/c0ae3237544746138fd4cea32f48f848) is 6.0M, max 48.2M, 42.2M free. Oct 21 00:31:13.859104 systemd-modules-load[194]: Inserted module 'overlay' Oct 21 00:31:13.865633 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 21 00:31:13.869725 systemd[1]: Started systemd-journald.service - Journal Service. Oct 21 00:31:13.869955 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 21 00:31:13.870598 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 21 00:31:13.874782 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 21 00:31:13.877819 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 21 00:31:13.883110 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 21 00:31:13.887286 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 21 00:31:13.889889 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 21 00:31:13.894339 systemd-modules-load[194]: Inserted module 'br_netfilter' Oct 21 00:31:13.895090 kernel: Bridge firewalling registered Oct 21 00:31:13.895597 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 21 00:31:13.896872 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 21 00:31:13.897362 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 21 00:31:13.905149 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 21 00:31:13.906831 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 21 00:31:13.909066 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 21 00:31:13.911185 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 21 00:31:13.918576 dracut-cmdline[229]: dracut-dracut-053 Oct 21 00:31:13.921277 dracut-cmdline[229]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro 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 verity.usrhash=e5ecef46f2b4a9e3a4706a3c1f0c4d71a161869371286766f303abb150208cb2 Oct 21 00:31:13.931587 systemd-resolved[232]: Positive Trust Anchors: Oct 21 00:31:13.931600 systemd-resolved[232]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 21 00:31:13.931625 systemd-resolved[232]: 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 21 00:31:13.933477 systemd-resolved[232]: Defaulting to hostname 'linux'. Oct 21 00:31:13.934156 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 21 00:31:13.934996 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 21 00:31:13.993741 kernel: SCSI subsystem initialized Oct 21 00:31:14.001743 kernel: Loading iSCSI transport class v2.0-870. Oct 21 00:31:14.011741 kernel: iscsi: registered transport (tcp) Oct 21 00:31:14.029751 kernel: iscsi: registered transport (qla4xxx) Oct 21 00:31:14.029782 kernel: QLogic iSCSI HBA Driver Oct 21 00:31:14.058882 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 21 00:31:14.072890 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 21 00:31:14.093344 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 21 00:31:14.093394 kernel: device-mapper: uevent: version 1.0.3 Oct 21 00:31:14.094705 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 21 00:31:14.130748 kernel: raid6: avx512x4 gen() 41813 MB/s Oct 21 00:31:14.147746 kernel: raid6: avx512x2 gen() 43341 MB/s Oct 21 00:31:14.164731 kernel: raid6: avx512x1 gen() 32156 MB/s Oct 21 00:31:14.181723 kernel: raid6: avx2x4 gen() 37601 MB/s Oct 21 00:31:14.198743 kernel: raid6: avx2x2 gen() 37321 MB/s Oct 21 00:31:14.216258 kernel: raid6: avx2x1 gen() 28916 MB/s Oct 21 00:31:14.216280 kernel: raid6: using algorithm avx512x2 gen() 43341 MB/s Oct 21 00:31:14.234300 kernel: raid6: .... xor() 30801 MB/s, rmw enabled Oct 21 00:31:14.234338 kernel: raid6: using avx512x2 recovery algorithm Oct 21 00:31:14.252757 kernel: xor: automatically using best checksumming function avx Oct 21 00:31:14.378744 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 21 00:31:14.388033 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 21 00:31:14.396878 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 21 00:31:14.408858 systemd-udevd[416]: Using default interface naming scheme 'v255'. Oct 21 00:31:14.412933 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 21 00:31:14.415917 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 21 00:31:14.428375 dracut-pre-trigger[425]: rd.md=0: removing MD RAID activation Oct 21 00:31:14.448847 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 21 00:31:14.458843 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 21 00:31:14.489104 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 21 00:31:14.495890 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 21 00:31:14.507731 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 21 00:31:14.511053 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 21 00:31:14.519361 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Oct 21 00:31:14.519542 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Oct 21 00:31:14.513590 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 21 00:31:14.516317 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 21 00:31:14.529252 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 21 00:31:14.529269 kernel: GPT:9289727 != 19775487 Oct 21 00:31:14.529277 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 21 00:31:14.529285 kernel: GPT:9289727 != 19775487 Oct 21 00:31:14.529293 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 21 00:31:14.529301 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 21 00:31:14.533823 kernel: cryptd: max_cpu_qlen set to 1000 Oct 21 00:31:14.535943 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 21 00:31:14.542220 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 21 00:31:14.543681 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 21 00:31:14.548388 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 21 00:31:14.548920 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 21 00:31:14.549083 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 21 00:31:14.552538 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 21 00:31:14.561600 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 21 00:31:14.563441 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 21 00:31:14.566780 kernel: libata version 3.00 loaded. Oct 21 00:31:14.572759 kernel: AVX2 version of gcm_enc/dec engaged. Oct 21 00:31:14.573748 kernel: AES CTR mode by8 optimization enabled Oct 21 00:31:14.576484 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 21 00:31:14.579340 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 21 00:31:14.587787 kernel: BTRFS: device fsid 2e79e9ad-c8a5-407d-a1ef-eafd66551d06 devid 1 transid 37 /dev/vda3 scanned by (udev-worker) (476) Oct 21 00:31:14.587822 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (475) Oct 21 00:31:14.593225 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 21 00:31:14.599108 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 21 00:31:14.603667 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 21 00:31:14.605214 kernel: ahci 0000:00:1f.2: version 3.0 Oct 21 00:31:14.605334 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Oct 21 00:31:14.604980 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 21 00:31:14.608547 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Oct 21 00:31:14.608664 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Oct 21 00:31:14.612879 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 21 00:31:14.614996 kernel: scsi host0: ahci Oct 21 00:31:14.615103 kernel: scsi host1: ahci Oct 21 00:31:14.615908 kernel: scsi host2: ahci Oct 21 00:31:14.616886 kernel: scsi host3: ahci Oct 21 00:31:14.618846 kernel: scsi host4: ahci Oct 21 00:31:14.621125 kernel: scsi host5: ahci Oct 21 00:31:14.621275 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Oct 21 00:31:14.621289 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Oct 21 00:31:14.623222 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Oct 21 00:31:14.623252 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Oct 21 00:31:14.625360 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Oct 21 00:31:14.625375 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Oct 21 00:31:14.626867 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 21 00:31:14.628613 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 21 00:31:14.633942 disk-uuid[572]: Primary Header is updated. Oct 21 00:31:14.633942 disk-uuid[572]: Secondary Entries is updated. Oct 21 00:31:14.633942 disk-uuid[572]: Secondary Header is updated. Oct 21 00:31:14.638300 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 21 00:31:14.941744 kernel: ata5: SATA link down (SStatus 0 SControl 300) Oct 21 00:31:14.941816 kernel: ata2: SATA link down (SStatus 0 SControl 300) Oct 21 00:31:14.941828 kernel: ata4: SATA link down (SStatus 0 SControl 300) Oct 21 00:31:14.942726 kernel: ata6: SATA link down (SStatus 0 SControl 300) Oct 21 00:31:14.943719 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Oct 21 00:31:14.945716 kernel: ata1: SATA link down (SStatus 0 SControl 300) Oct 21 00:31:14.947135 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Oct 21 00:31:14.947150 kernel: ata3.00: applying bridge limits Oct 21 00:31:14.947910 kernel: ata3.00: configured for UDMA/100 Oct 21 00:31:14.948714 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Oct 21 00:31:14.988752 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Oct 21 00:31:15.015748 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 21 00:31:15.027732 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Oct 21 00:31:15.645727 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 21 00:31:15.646053 disk-uuid[577]: The operation has completed successfully. Oct 21 00:31:15.666895 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 21 00:31:15.666996 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 21 00:31:15.686854 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 21 00:31:15.692156 sh[594]: Success Oct 21 00:31:15.703724 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Oct 21 00:31:15.726727 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 21 00:31:15.739657 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 21 00:31:15.741953 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 21 00:31:15.750771 kernel: BTRFS info (device dm-0): first mount of filesystem 2e79e9ad-c8a5-407d-a1ef-eafd66551d06 Oct 21 00:31:15.750804 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 21 00:31:15.753274 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 21 00:31:15.753302 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 21 00:31:15.755208 kernel: BTRFS info (device dm-0): using free space tree Oct 21 00:31:15.758938 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 21 00:31:15.761337 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 21 00:31:15.770847 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 21 00:31:15.773197 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 21 00:31:15.782749 kernel: BTRFS info (device vda6): first mount of filesystem 5f93bbde-dff1-4b7a-87e1-224f2915dc86 Oct 21 00:31:15.782775 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 21 00:31:15.782783 kernel: BTRFS info (device vda6): using free space tree Oct 21 00:31:15.786878 kernel: BTRFS info (device vda6): auto enabling async discard Oct 21 00:31:15.793400 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 21 00:31:15.796914 kernel: BTRFS info (device vda6): last unmount of filesystem 5f93bbde-dff1-4b7a-87e1-224f2915dc86 Oct 21 00:31:15.801792 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 21 00:31:15.813816 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 21 00:31:15.857531 ignition[691]: Ignition 2.19.0 Oct 21 00:31:15.857542 ignition[691]: Stage: fetch-offline Oct 21 00:31:15.857566 ignition[691]: no configs at "/usr/lib/ignition/base.d" Oct 21 00:31:15.857573 ignition[691]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 21 00:31:15.857643 ignition[691]: parsed url from cmdline: "" Oct 21 00:31:15.857645 ignition[691]: no config URL provided Oct 21 00:31:15.857648 ignition[691]: reading system config file "/usr/lib/ignition/user.ign" Oct 21 00:31:15.857653 ignition[691]: no config at "/usr/lib/ignition/user.ign" Oct 21 00:31:15.857672 ignition[691]: op(1): [started] loading QEMU firmware config module Oct 21 00:31:15.857675 ignition[691]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 21 00:31:15.866966 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 21 00:31:15.869453 ignition[691]: op(1): [finished] loading QEMU firmware config module Oct 21 00:31:15.870613 ignition[691]: parsing config with SHA512: fe647ebacd720f1ec30507aabbe027c5861299f51816da8933361e6765a51adf51ef0ba66355db86d53d3e0948f2d8b6e38cd662e764267e69385ab70dc70643 Oct 21 00:31:15.872676 unknown[691]: fetched base config from "system" Oct 21 00:31:15.873323 unknown[691]: fetched user config from "qemu" Oct 21 00:31:15.873561 ignition[691]: fetch-offline: fetch-offline passed Oct 21 00:31:15.873625 ignition[691]: Ignition finished successfully Oct 21 00:31:15.877845 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 21 00:31:15.880546 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 21 00:31:15.894060 systemd-networkd[782]: lo: Link UP Oct 21 00:31:15.894076 systemd-networkd[782]: lo: Gained carrier Oct 21 00:31:15.894932 systemd-networkd[782]: Enumeration completed Oct 21 00:31:15.894990 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 21 00:31:15.895326 systemd-networkd[782]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 21 00:31:15.895328 systemd-networkd[782]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 21 00:31:15.896123 systemd-networkd[782]: eth0: Link UP Oct 21 00:31:15.896125 systemd-networkd[782]: eth0: Gained carrier Oct 21 00:31:15.896131 systemd-networkd[782]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 21 00:31:15.896607 systemd[1]: Reached target network.target - Network. Oct 21 00:31:15.897113 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 21 00:31:15.912804 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 21 00:31:15.921756 systemd-networkd[782]: eth0: DHCPv4 address 10.0.0.136/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 21 00:31:15.923069 ignition[785]: Ignition 2.19.0 Oct 21 00:31:15.923074 ignition[785]: Stage: kargs Oct 21 00:31:15.923189 ignition[785]: no configs at "/usr/lib/ignition/base.d" Oct 21 00:31:15.923200 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 21 00:31:15.923671 ignition[785]: kargs: kargs passed Oct 21 00:31:15.926931 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 21 00:31:15.923726 ignition[785]: Ignition finished successfully Oct 21 00:31:15.935855 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 21 00:31:15.946050 ignition[794]: Ignition 2.19.0 Oct 21 00:31:15.946064 ignition[794]: Stage: disks Oct 21 00:31:15.946183 ignition[794]: no configs at "/usr/lib/ignition/base.d" Oct 21 00:31:15.946189 ignition[794]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 21 00:31:15.946670 ignition[794]: disks: disks passed Oct 21 00:31:15.949048 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 21 00:31:15.946728 ignition[794]: Ignition finished successfully Oct 21 00:31:15.951765 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 21 00:31:15.953542 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 21 00:31:15.956191 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 21 00:31:15.957464 systemd[1]: Reached target sysinit.target - System Initialization. Oct 21 00:31:15.958874 systemd[1]: Reached target basic.target - Basic System. Oct 21 00:31:15.969881 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 21 00:31:15.981185 systemd-fsck[805]: ROOT: clean, 14/553520 files, 52654/553472 blocks Oct 21 00:31:15.985119 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 21 00:31:15.997804 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 21 00:31:16.106487 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 21 00:31:16.108384 kernel: EXT4-fs (vda9): mounted filesystem 1806bbb1-1d28-482e-b646-3707432f1e1b r/w with ordered data mode. Quota mode: none. Oct 21 00:31:16.108902 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 21 00:31:16.121808 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 21 00:31:16.123496 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 21 00:31:16.134138 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (813) Oct 21 00:31:16.134166 kernel: BTRFS info (device vda6): first mount of filesystem 5f93bbde-dff1-4b7a-87e1-224f2915dc86 Oct 21 00:31:16.134175 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 21 00:31:16.134183 kernel: BTRFS info (device vda6): using free space tree Oct 21 00:31:16.134190 kernel: BTRFS info (device vda6): auto enabling async discard Oct 21 00:31:16.125820 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 21 00:31:16.125847 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 21 00:31:16.125862 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 21 00:31:16.135661 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 21 00:31:16.137968 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 21 00:31:16.142614 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 21 00:31:16.170379 initrd-setup-root[837]: cut: /sysroot/etc/passwd: No such file or directory Oct 21 00:31:16.173456 initrd-setup-root[844]: cut: /sysroot/etc/group: No such file or directory Oct 21 00:31:16.176192 initrd-setup-root[851]: cut: /sysroot/etc/shadow: No such file or directory Oct 21 00:31:16.179817 initrd-setup-root[858]: cut: /sysroot/etc/gshadow: No such file or directory Oct 21 00:31:16.237491 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 21 00:31:16.248786 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 21 00:31:16.249822 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 21 00:31:16.258725 kernel: BTRFS info (device vda6): last unmount of filesystem 5f93bbde-dff1-4b7a-87e1-224f2915dc86 Oct 21 00:31:16.268746 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 21 00:31:16.277301 ignition[926]: INFO : Ignition 2.19.0 Oct 21 00:31:16.277301 ignition[926]: INFO : Stage: mount Oct 21 00:31:16.279056 ignition[926]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 21 00:31:16.279056 ignition[926]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 21 00:31:16.279056 ignition[926]: INFO : mount: mount passed Oct 21 00:31:16.279056 ignition[926]: INFO : Ignition finished successfully Oct 21 00:31:16.278912 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 21 00:31:16.285804 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 21 00:31:16.750465 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 21 00:31:16.762887 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 21 00:31:16.771681 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (939) Oct 21 00:31:16.771725 kernel: BTRFS info (device vda6): first mount of filesystem 5f93bbde-dff1-4b7a-87e1-224f2915dc86 Oct 21 00:31:16.771739 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 21 00:31:16.773720 kernel: BTRFS info (device vda6): using free space tree Oct 21 00:31:16.776716 kernel: BTRFS info (device vda6): auto enabling async discard Oct 21 00:31:16.777486 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 21 00:31:16.812359 ignition[956]: INFO : Ignition 2.19.0 Oct 21 00:31:16.812359 ignition[956]: INFO : Stage: files Oct 21 00:31:16.814525 ignition[956]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 21 00:31:16.814525 ignition[956]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 21 00:31:16.814525 ignition[956]: DEBUG : files: compiled without relabeling support, skipping Oct 21 00:31:16.814525 ignition[956]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 21 00:31:16.814525 ignition[956]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 21 00:31:16.822392 ignition[956]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 21 00:31:16.822392 ignition[956]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 21 00:31:16.822392 ignition[956]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Oct 21 00:31:16.822392 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.36.1-x86-64.raw: attempt #1 Oct 21 00:31:16.815862 unknown[956]: wrote ssh authorized keys file for user: core Oct 21 00:31:16.939506 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 21 00:31:17.149035 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Oct 21 00:31:17.149035 ignition[956]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 21 00:31:17.154420 ignition[956]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 21 00:31:17.156984 ignition[956]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 21 00:31:17.156984 ignition[956]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 21 00:31:17.156984 ignition[956]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 21 00:31:17.174735 ignition[956]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 21 00:31:17.178652 ignition[956]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 21 00:31:17.180792 ignition[956]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 21 00:31:17.180792 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 21 00:31:17.180792 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 21 00:31:17.180792 ignition[956]: INFO : files: files passed Oct 21 00:31:17.180792 ignition[956]: INFO : Ignition finished successfully Oct 21 00:31:17.185252 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 21 00:31:17.205876 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 21 00:31:17.207841 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 21 00:31:17.210102 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 21 00:31:17.210180 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 21 00:31:17.216922 initrd-setup-root-after-ignition[984]: grep: /sysroot/oem/oem-release: No such file or directory Oct 21 00:31:17.220434 initrd-setup-root-after-ignition[986]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 21 00:31:17.220434 initrd-setup-root-after-ignition[986]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 21 00:31:17.224096 initrd-setup-root-after-ignition[990]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 21 00:31:17.226760 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 21 00:31:17.227403 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 21 00:31:17.243867 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 21 00:31:17.260058 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 21 00:31:17.260150 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 21 00:31:17.262917 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 21 00:31:17.263458 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 21 00:31:17.267088 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 21 00:31:17.268172 systemd-networkd[782]: eth0: Gained IPv6LL Oct 21 00:31:17.285830 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 21 00:31:17.296083 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 21 00:31:17.303863 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 21 00:31:17.313852 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 21 00:31:17.314444 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 21 00:31:17.317123 systemd[1]: Stopped target timers.target - Timer Units. Oct 21 00:31:17.319772 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 21 00:31:17.319868 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 21 00:31:17.323938 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 21 00:31:17.326474 systemd[1]: Stopped target basic.target - Basic System. Oct 21 00:31:17.327170 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 21 00:31:17.330035 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 21 00:31:17.332441 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 21 00:31:17.335051 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 21 00:31:17.337550 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 21 00:31:17.340041 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 21 00:31:17.343138 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 21 00:31:17.345205 systemd[1]: Stopped target swap.target - Swaps. Oct 21 00:31:17.347421 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 21 00:31:17.347541 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 21 00:31:17.351111 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 21 00:31:17.353497 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 21 00:31:17.354175 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 21 00:31:17.354263 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 21 00:31:17.358112 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 21 00:31:17.358226 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 21 00:31:17.362727 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 21 00:31:17.362837 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 21 00:31:17.365170 systemd[1]: Stopped target paths.target - Path Units. Oct 21 00:31:17.367235 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 21 00:31:17.368433 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 21 00:31:17.371091 systemd[1]: Stopped target slices.target - Slice Units. Oct 21 00:31:17.371767 systemd[1]: Stopped target sockets.target - Socket Units. Oct 21 00:31:17.374591 systemd[1]: iscsid.socket: Deactivated successfully. Oct 21 00:31:17.374664 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 21 00:31:17.377307 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 21 00:31:17.377371 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 21 00:31:17.379346 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 21 00:31:17.379429 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 21 00:31:17.381538 systemd[1]: ignition-files.service: Deactivated successfully. Oct 21 00:31:17.381613 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 21 00:31:17.400917 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 21 00:31:17.401757 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 21 00:31:17.401892 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 21 00:31:17.416501 ignition[1010]: INFO : Ignition 2.19.0 Oct 21 00:31:17.416501 ignition[1010]: INFO : Stage: umount Oct 21 00:31:17.416501 ignition[1010]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 21 00:31:17.416501 ignition[1010]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 21 00:31:17.402726 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 21 00:31:17.425137 ignition[1010]: INFO : umount: umount passed Oct 21 00:31:17.425137 ignition[1010]: INFO : Ignition finished successfully Oct 21 00:31:17.402804 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 21 00:31:17.402905 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 21 00:31:17.403208 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 21 00:31:17.403283 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 21 00:31:17.406181 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 21 00:31:17.406272 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 21 00:31:17.419414 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 21 00:31:17.419551 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 21 00:31:17.422820 systemd[1]: Stopped target network.target - Network. Oct 21 00:31:17.424453 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 21 00:31:17.424532 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 21 00:31:17.429258 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 21 00:31:17.429290 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 21 00:31:17.434141 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 21 00:31:17.436176 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 21 00:31:17.443596 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 21 00:31:17.444989 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 21 00:31:17.446950 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 21 00:31:17.448349 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 21 00:31:17.453456 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 21 00:31:17.467037 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 21 00:31:17.467231 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 21 00:31:17.472991 systemd-networkd[782]: eth0: DHCPv6 lease lost Oct 21 00:31:17.473192 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 21 00:31:17.473260 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 21 00:31:17.476746 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 21 00:31:17.476829 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 21 00:31:17.481380 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 21 00:31:17.481461 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 21 00:31:17.484356 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 21 00:31:17.484444 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 21 00:31:17.487856 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 21 00:31:17.487895 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 21 00:31:17.496820 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 21 00:31:17.500195 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 21 00:31:17.500254 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 21 00:31:17.503441 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 21 00:31:17.503481 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 21 00:31:17.504237 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 21 00:31:17.504270 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 21 00:31:17.506556 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 21 00:31:17.516328 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 21 00:31:17.516407 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 21 00:31:17.538404 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 21 00:31:17.538580 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 21 00:31:17.539430 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 21 00:31:17.539471 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 21 00:31:17.543501 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 21 00:31:17.543560 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 21 00:31:17.548632 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 21 00:31:17.548680 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 21 00:31:17.552834 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 21 00:31:17.552874 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 21 00:31:17.555949 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 21 00:31:17.556001 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 21 00:31:17.569901 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 21 00:31:17.570621 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 21 00:31:17.570673 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 21 00:31:17.575121 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 21 00:31:17.575156 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 21 00:31:17.578319 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 21 00:31:17.578395 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 21 00:31:17.581681 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 21 00:31:17.583071 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 21 00:31:17.592776 systemd[1]: Switching root. Oct 21 00:31:17.629198 systemd-journald[192]: Journal stopped Oct 21 00:31:18.382667 systemd-journald[192]: Received SIGTERM from PID 1 (systemd). Oct 21 00:31:18.383147 kernel: SELinux: policy capability network_peer_controls=1 Oct 21 00:31:18.383473 kernel: SELinux: policy capability open_perms=1 Oct 21 00:31:18.383496 kernel: SELinux: policy capability extended_socket_class=1 Oct 21 00:31:18.383510 kernel: SELinux: policy capability always_check_network=0 Oct 21 00:31:18.383544 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 21 00:31:18.383563 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 21 00:31:18.383577 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 21 00:31:18.383590 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 21 00:31:18.383602 kernel: audit: type=1403 audit(1792542677.723:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 21 00:31:18.383621 systemd[1]: Successfully loaded SELinux policy in 40.523ms. Oct 21 00:31:18.383639 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 8.479ms. Oct 21 00:31:18.383648 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 21 00:31:18.383656 systemd[1]: Detected virtualization kvm. Oct 21 00:31:18.383665 systemd[1]: Detected architecture x86-64. Oct 21 00:31:18.383679 systemd[1]: Detected first boot. Oct 21 00:31:18.383727 systemd[1]: Initializing machine ID from VM UUID. Oct 21 00:31:18.383742 zram_generator::config[1054]: No configuration found. Oct 21 00:31:18.383763 systemd[1]: Populated /etc with preset unit settings. Oct 21 00:31:18.383777 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 21 00:31:18.383789 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 21 00:31:18.383803 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 21 00:31:18.383823 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 21 00:31:18.383842 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 21 00:31:18.383859 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 21 00:31:18.383872 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 21 00:31:18.383889 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 21 00:31:18.383898 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 21 00:31:18.383905 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 21 00:31:18.383918 systemd[1]: Created slice user.slice - User and Session Slice. Oct 21 00:31:18.383933 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 21 00:31:18.383946 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 21 00:31:18.383959 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 21 00:31:18.383975 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 21 00:31:18.383983 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 21 00:31:18.383998 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 21 00:31:18.384013 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 21 00:31:18.384028 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 21 00:31:18.384043 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 21 00:31:18.384057 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 21 00:31:18.384073 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 21 00:31:18.384090 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 21 00:31:18.384103 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 21 00:31:18.384115 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 21 00:31:18.384129 systemd[1]: Reached target slices.target - Slice Units. Oct 21 00:31:18.384141 systemd[1]: Reached target swap.target - Swaps. Oct 21 00:31:18.384153 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 21 00:31:18.384167 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 21 00:31:18.384175 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 21 00:31:18.384192 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 21 00:31:18.384206 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 21 00:31:18.384218 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 21 00:31:18.384230 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 21 00:31:18.384243 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 21 00:31:18.384252 systemd[1]: Mounting media.mount - External Media Directory... Oct 21 00:31:18.384260 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 21 00:31:18.384268 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 21 00:31:18.384277 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 21 00:31:18.384286 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 21 00:31:18.384294 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 21 00:31:18.384302 systemd[1]: Reached target machines.target - Containers. Oct 21 00:31:18.384309 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 21 00:31:18.384317 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 21 00:31:18.384325 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 21 00:31:18.384333 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 21 00:31:18.384340 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 21 00:31:18.384351 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 21 00:31:18.384365 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 21 00:31:18.384381 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 21 00:31:18.384394 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 21 00:31:18.384405 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 21 00:31:18.384413 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 21 00:31:18.384421 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 21 00:31:18.384429 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 21 00:31:18.384437 systemd[1]: Stopped systemd-fsck-usr.service. Oct 21 00:31:18.384446 kernel: loop: module loaded Oct 21 00:31:18.384453 kernel: fuse: init (API version 7.39) Oct 21 00:31:18.384461 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 21 00:31:18.384468 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 21 00:31:18.384476 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 21 00:31:18.384499 systemd-journald[1135]: Collecting audit messages is disabled. Oct 21 00:31:18.384545 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 21 00:31:18.384555 systemd-journald[1135]: Journal started Oct 21 00:31:18.384574 systemd-journald[1135]: Runtime Journal (/run/log/journal/c0ae3237544746138fd4cea32f48f848) is 6.0M, max 48.2M, 42.2M free. Oct 21 00:31:18.096316 systemd[1]: Queued start job for default target multi-user.target. Oct 21 00:31:18.113262 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 21 00:31:18.113764 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 21 00:31:18.389121 kernel: ACPI: bus type drm_connector registered Oct 21 00:31:18.391886 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 21 00:31:18.395203 systemd[1]: verity-setup.service: Deactivated successfully. Oct 21 00:31:18.395252 systemd[1]: Stopped verity-setup.service. Oct 21 00:31:18.398730 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 21 00:31:18.401946 systemd[1]: Started systemd-journald.service - Journal Service. Oct 21 00:31:18.403509 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 21 00:31:18.405487 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 21 00:31:18.407256 systemd[1]: Mounted media.mount - External Media Directory. Oct 21 00:31:18.408912 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 21 00:31:18.410957 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 21 00:31:18.412789 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 21 00:31:18.414580 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 21 00:31:18.416678 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 21 00:31:18.418899 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 21 00:31:18.419019 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 21 00:31:18.421116 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 21 00:31:18.421252 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 21 00:31:18.423253 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 21 00:31:18.423389 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 21 00:31:18.425409 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 21 00:31:18.425830 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 21 00:31:18.428331 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 21 00:31:18.428580 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 21 00:31:18.431064 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 21 00:31:18.431352 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 21 00:31:18.433629 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 21 00:31:18.435884 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 21 00:31:18.438572 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 21 00:31:18.449059 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 21 00:31:18.461243 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 21 00:31:18.464339 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 21 00:31:18.466247 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 21 00:31:18.466293 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 21 00:31:18.468991 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 21 00:31:18.471874 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 21 00:31:18.474771 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 21 00:31:18.475657 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 21 00:31:18.477161 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 21 00:31:18.479779 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 21 00:31:18.481785 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 21 00:31:18.482733 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 21 00:31:18.484775 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 21 00:31:18.485619 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 21 00:31:18.497051 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 21 00:31:18.500945 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 21 00:31:18.502444 systemd-journald[1135]: Time spent on flushing to /var/log/journal/c0ae3237544746138fd4cea32f48f848 is 15.623ms for 975 entries. Oct 21 00:31:18.502444 systemd-journald[1135]: System Journal (/var/log/journal/c0ae3237544746138fd4cea32f48f848) is 8.0M, max 195.6M, 187.6M free. Oct 21 00:31:18.529983 systemd-journald[1135]: Received client request to flush runtime journal. Oct 21 00:31:18.530024 kernel: loop0: detected capacity change from 0 to 219840 Oct 21 00:31:18.504208 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 21 00:31:18.506874 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 21 00:31:18.509939 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 21 00:31:18.512627 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 21 00:31:18.514950 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 21 00:31:18.521646 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 21 00:31:18.533776 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 21 00:31:18.539735 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 21 00:31:18.539869 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 21 00:31:18.543133 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 21 00:31:18.546082 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 21 00:31:18.557168 udevadm[1182]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 21 00:31:18.561343 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 21 00:31:18.561941 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 21 00:31:18.571644 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 21 00:31:18.578989 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 21 00:31:18.584926 kernel: loop1: detected capacity change from 0 to 140768 Oct 21 00:31:18.599925 systemd-tmpfiles[1188]: ACLs are not supported, ignoring. Oct 21 00:31:18.599954 systemd-tmpfiles[1188]: ACLs are not supported, ignoring. Oct 21 00:31:18.604197 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 21 00:31:18.621788 kernel: loop2: detected capacity change from 0 to 142488 Oct 21 00:31:18.657863 kernel: loop3: detected capacity change from 0 to 219840 Oct 21 00:31:18.671098 kernel: loop4: detected capacity change from 0 to 140768 Oct 21 00:31:18.688729 kernel: loop5: detected capacity change from 0 to 142488 Oct 21 00:31:18.700326 (sd-merge)[1196]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Oct 21 00:31:18.700871 (sd-merge)[1196]: Merged extensions into '/usr'. Oct 21 00:31:18.708127 systemd[1]: Reloading requested from client PID 1168 ('systemd-sysext') (unit systemd-sysext.service)... Oct 21 00:31:18.708218 systemd[1]: Reloading... Oct 21 00:31:18.755731 zram_generator::config[1222]: No configuration found. Oct 21 00:31:18.811043 ldconfig[1163]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 21 00:31:18.867315 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 21 00:31:18.910849 systemd[1]: Reloading finished in 202 ms. Oct 21 00:31:18.938488 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 21 00:31:18.940563 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 21 00:31:18.953972 systemd[1]: Starting ensure-sysext.service... Oct 21 00:31:18.956459 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 21 00:31:18.963922 systemd[1]: Reloading requested from client PID 1259 ('systemctl') (unit ensure-sysext.service)... Oct 21 00:31:18.963957 systemd[1]: Reloading... Oct 21 00:31:18.975151 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 21 00:31:18.975627 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 21 00:31:18.976196 systemd-tmpfiles[1260]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 21 00:31:18.976458 systemd-tmpfiles[1260]: ACLs are not supported, ignoring. Oct 21 00:31:18.976516 systemd-tmpfiles[1260]: ACLs are not supported, ignoring. Oct 21 00:31:18.978936 systemd-tmpfiles[1260]: Detected autofs mount point /boot during canonicalization of boot. Oct 21 00:31:18.978951 systemd-tmpfiles[1260]: Skipping /boot Oct 21 00:31:18.984672 systemd-tmpfiles[1260]: Detected autofs mount point /boot during canonicalization of boot. Oct 21 00:31:18.984958 systemd-tmpfiles[1260]: Skipping /boot Oct 21 00:31:19.016331 zram_generator::config[1284]: No configuration found. Oct 21 00:31:19.153478 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 21 00:31:19.182013 systemd[1]: Reloading finished in 217 ms. Oct 21 00:31:19.198434 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 21 00:31:19.211554 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 21 00:31:19.220017 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Oct 21 00:31:19.222946 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 21 00:31:19.225604 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 21 00:31:19.231924 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 21 00:31:19.234961 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 21 00:31:19.239655 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 21 00:31:19.243258 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 21 00:31:19.243430 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 21 00:31:19.245396 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 21 00:31:19.250985 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 21 00:31:19.254724 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 21 00:31:19.256715 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 21 00:31:19.258614 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 21 00:31:19.260188 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 21 00:31:19.260944 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 21 00:31:19.263060 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 21 00:31:19.263164 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 21 00:31:19.265411 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 21 00:31:19.265596 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 21 00:31:19.266927 systemd-udevd[1337]: Using default interface naming scheme 'v255'. Oct 21 00:31:19.268605 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 21 00:31:19.268761 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 21 00:31:19.275734 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 21 00:31:19.281947 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 21 00:31:19.283082 augenrules[1355]: No rules Oct 21 00:31:19.282220 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 21 00:31:19.288950 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 21 00:31:19.292037 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 21 00:31:19.297544 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 21 00:31:19.300967 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 21 00:31:19.302669 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 21 00:31:19.303880 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 21 00:31:19.305863 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 21 00:31:19.306401 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 21 00:31:19.308424 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 21 00:31:19.310786 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Oct 21 00:31:19.312647 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 21 00:31:19.314975 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 21 00:31:19.315078 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 21 00:31:19.317155 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 21 00:31:19.317255 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 21 00:31:19.319019 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 21 00:31:19.319126 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 21 00:31:19.322080 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 21 00:31:19.322191 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 21 00:31:19.324123 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 21 00:31:19.330095 systemd[1]: Finished ensure-sysext.service. Oct 21 00:31:19.338723 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1381) Oct 21 00:31:19.337426 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 21 00:31:19.345934 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 21 00:31:19.348028 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 21 00:31:19.348091 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 21 00:31:19.351835 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 21 00:31:19.353406 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 21 00:31:19.361295 systemd-resolved[1330]: Positive Trust Anchors: Oct 21 00:31:19.361307 systemd-resolved[1330]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 21 00:31:19.361332 systemd-resolved[1330]: 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 21 00:31:19.361814 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 21 00:31:19.365619 systemd-resolved[1330]: Defaulting to hostname 'linux'. Oct 21 00:31:19.372454 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 21 00:31:19.374147 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 21 00:31:19.378570 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 21 00:31:19.384050 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 21 00:31:19.389745 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Oct 21 00:31:19.401722 kernel: ACPI: button: Power Button [PWRF] Oct 21 00:31:19.407434 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 21 00:31:19.409296 systemd[1]: Reached target time-set.target - System Time Set. Oct 21 00:31:19.414190 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Oct 21 00:31:19.414479 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Oct 21 00:31:19.414590 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Oct 21 00:31:19.416926 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Oct 21 00:31:19.419754 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Oct 21 00:31:19.420238 systemd-networkd[1398]: lo: Link UP Oct 21 00:31:19.420258 systemd-networkd[1398]: lo: Gained carrier Oct 21 00:31:19.421155 systemd-networkd[1398]: Enumeration completed Oct 21 00:31:19.421217 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 21 00:31:19.423065 systemd-networkd[1398]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 21 00:31:19.423073 systemd-networkd[1398]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 21 00:31:19.424140 systemd[1]: Reached target network.target - Network. Oct 21 00:31:19.428218 systemd-networkd[1398]: eth0: Link UP Oct 21 00:31:19.428222 systemd-networkd[1398]: eth0: Gained carrier Oct 21 00:31:19.428235 systemd-networkd[1398]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 21 00:31:19.433088 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 21 00:31:19.440771 systemd-networkd[1398]: eth0: DHCPv4 address 10.0.0.136/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 21 00:31:19.441660 systemd-timesyncd[1400]: Network configuration changed, trying to establish connection. Oct 21 00:31:20.512931 systemd-timesyncd[1400]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 21 00:31:20.512981 systemd-timesyncd[1400]: Initial clock synchronization to Wed 2026-10-21 00:31:20.512738 UTC. Oct 21 00:31:20.513134 systemd-resolved[1330]: Clock change detected. Flushing caches. Oct 21 00:31:20.513322 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 21 00:31:20.518374 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 21 00:31:20.518533 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 21 00:31:20.523244 kernel: mousedev: PS/2 mouse device common for all mice Oct 21 00:31:20.527536 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 21 00:31:20.572261 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 21 00:31:20.648040 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 21 00:31:20.660574 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 21 00:31:20.668140 lvm[1426]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 21 00:31:20.698851 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 21 00:31:20.700922 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 21 00:31:20.702472 systemd[1]: Reached target sysinit.target - System Initialization. Oct 21 00:31:20.703989 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 21 00:31:20.705699 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 21 00:31:20.707529 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 21 00:31:20.709145 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 21 00:31:20.710825 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 21 00:31:20.712471 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 21 00:31:20.712500 systemd[1]: Reached target paths.target - Path Units. Oct 21 00:31:20.713728 systemd[1]: Reached target timers.target - Timer Units. Oct 21 00:31:20.715603 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 21 00:31:20.718073 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 21 00:31:20.726938 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 21 00:31:20.729237 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 21 00:31:20.731027 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 21 00:31:20.732492 systemd[1]: Reached target sockets.target - Socket Units. Oct 21 00:31:20.733744 systemd[1]: Reached target basic.target - Basic System. Oct 21 00:31:20.734948 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 21 00:31:20.734978 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 21 00:31:20.735688 systemd[1]: Starting containerd.service - containerd container runtime... Oct 21 00:31:20.737574 lvm[1430]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 21 00:31:20.737818 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 21 00:31:20.739544 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 21 00:31:20.743830 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 21 00:31:20.748505 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 21 00:31:20.749212 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 21 00:31:20.753475 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 21 00:31:20.755625 jq[1433]: false Oct 21 00:31:20.757378 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 21 00:31:20.763430 extend-filesystems[1434]: Found loop3 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found loop4 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found loop5 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found sr0 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda1 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda2 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda3 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found usr Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda4 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda6 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda7 Oct 21 00:31:20.763430 extend-filesystems[1434]: Found vda9 Oct 21 00:31:20.763430 extend-filesystems[1434]: Checking size of /dev/vda9 Oct 21 00:31:20.794662 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1381) Oct 21 00:31:20.794715 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Oct 21 00:31:20.761376 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 21 00:31:20.795092 extend-filesystems[1434]: Resized partition /dev/vda9 Oct 21 00:31:20.773837 dbus-daemon[1432]: [system] SELinux support is enabled Oct 21 00:31:20.762988 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 21 00:31:20.801137 extend-filesystems[1453]: resize2fs 1.47.1 (20-May-2024) Oct 21 00:31:20.763406 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 21 00:31:20.770635 systemd[1]: Starting update-engine.service - Update Engine... Oct 21 00:31:20.786389 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 21 00:31:20.803421 jq[1452]: true Oct 21 00:31:20.787510 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 21 00:31:20.809417 update_engine[1446]: I20261021 00:31:20.807329 1446 main.cc:92] Flatcar Update Engine starting Oct 21 00:31:20.809417 update_engine[1446]: I20261021 00:31:20.808367 1446 update_check_scheduler.cc:74] Next update check in 5m0s Oct 21 00:31:20.791804 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 21 00:31:20.807610 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 21 00:31:20.807759 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 21 00:31:20.807970 systemd[1]: motdgen.service: Deactivated successfully. Oct 21 00:31:20.808118 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 21 00:31:20.810099 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 21 00:31:20.810202 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 21 00:31:20.816679 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Oct 21 00:31:20.820207 jq[1457]: true Oct 21 00:31:20.821746 (ntainerd)[1458]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 21 00:31:20.836192 extend-filesystems[1453]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 21 00:31:20.836192 extend-filesystems[1453]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 21 00:31:20.836192 extend-filesystems[1453]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Oct 21 00:31:20.830932 systemd[1]: Started update-engine.service - Update Engine. Oct 21 00:31:20.848640 extend-filesystems[1434]: Resized filesystem in /dev/vda9 Oct 21 00:31:20.832750 systemd-logind[1441]: Watching system buttons on /dev/input/event1 (Power Button) Oct 21 00:31:20.832761 systemd-logind[1441]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 21 00:31:20.833739 systemd-logind[1441]: New seat seat0. Oct 21 00:31:20.835086 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 21 00:31:20.835105 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 21 00:31:20.840159 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 21 00:31:20.840172 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 21 00:31:20.848402 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 21 00:31:20.849150 systemd[1]: Started systemd-logind.service - User Login Management. Oct 21 00:31:20.853822 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 21 00:31:20.854003 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 21 00:31:20.859542 bash[1483]: Updated "/home/core/.ssh/authorized_keys" Oct 21 00:31:20.861969 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 21 00:31:20.864866 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 21 00:31:20.883566 locksmithd[1476]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 21 00:31:20.949965 sshd_keygen[1454]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 21 00:31:20.968138 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 21 00:31:20.970585 containerd[1458]: time="2026-10-21T00:31:20.970517000Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Oct 21 00:31:20.977448 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 21 00:31:20.984559 systemd[1]: issuegen.service: Deactivated successfully. Oct 21 00:31:20.984695 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 21 00:31:20.987781 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 21 00:31:20.989115 containerd[1458]: time="2026-10-21T00:31:20.987990801Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989139 containerd[1458]: time="2026-10-21T00:31:20.989112566Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.159-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989139 containerd[1458]: time="2026-10-21T00:31:20.989131973Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 21 00:31:20.989178 containerd[1458]: time="2026-10-21T00:31:20.989145699Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 21 00:31:20.989304 containerd[1458]: time="2026-10-21T00:31:20.989288904Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 21 00:31:20.989453 containerd[1458]: time="2026-10-21T00:31:20.989362074Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989453 containerd[1458]: time="2026-10-21T00:31:20.989404172Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989453 containerd[1458]: time="2026-10-21T00:31:20.989413332Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989607 containerd[1458]: time="2026-10-21T00:31:20.989596232Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989636 containerd[1458]: time="2026-10-21T00:31:20.989630813Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989859 containerd[1458]: time="2026-10-21T00:31:20.989657911Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989859 containerd[1458]: time="2026-10-21T00:31:20.989667607Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989859 containerd[1458]: time="2026-10-21T00:31:20.989719093Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989859 containerd[1458]: time="2026-10-21T00:31:20.989842802Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 21 00:31:20.989997 containerd[1458]: time="2026-10-21T00:31:20.989987071Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 21 00:31:20.990024 containerd[1458]: time="2026-10-21T00:31:20.990018126Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 21 00:31:20.990125 containerd[1458]: time="2026-10-21T00:31:20.990116808Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 21 00:31:20.990185 containerd[1458]: time="2026-10-21T00:31:20.990178317Z" level=info msg="metadata content store policy set" policy=shared Oct 21 00:31:20.994370 containerd[1458]: time="2026-10-21T00:31:20.994345354Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 21 00:31:20.994406 containerd[1458]: time="2026-10-21T00:31:20.994391570Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 21 00:31:20.994472 containerd[1458]: time="2026-10-21T00:31:20.994440340Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 21 00:31:20.994491 containerd[1458]: time="2026-10-21T00:31:20.994477092Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 21 00:31:20.994491 containerd[1458]: time="2026-10-21T00:31:20.994488247Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 21 00:31:20.994591 containerd[1458]: time="2026-10-21T00:31:20.994575499Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.994964251Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995141535Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995159172Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995172391Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995203026Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995242955Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995257650Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995271328Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995285698Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995299902Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995340870Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995352063Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995371213Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995572 containerd[1458]: time="2026-10-21T00:31:20.995385095Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995398015Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995411299Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995423606Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995436263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995521814Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995544211Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995558484Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995575842Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995589778Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995603302Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995616228Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995630590Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995651381Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995667170Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.995845 containerd[1458]: time="2026-10-21T00:31:20.995678182Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995719592Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995786012Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995799532Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995811099Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995819292Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995836929Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995848986Z" level=info msg="NRI interface is disabled by configuration." Oct 21 00:31:20.996027 containerd[1458]: time="2026-10-21T00:31:20.995856911Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 21 00:31:20.996145 containerd[1458]: time="2026-10-21T00:31:20.996112912Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 21 00:31:20.996272 containerd[1458]: time="2026-10-21T00:31:20.996157899Z" level=info msg="Connect containerd service" Oct 21 00:31:20.996272 containerd[1458]: time="2026-10-21T00:31:20.996186510Z" level=info msg="using legacy CRI server" Oct 21 00:31:20.996272 containerd[1458]: time="2026-10-21T00:31:20.996191774Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 21 00:31:20.996311 containerd[1458]: time="2026-10-21T00:31:20.996297769Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 21 00:31:20.996838 containerd[1458]: time="2026-10-21T00:31:20.996771170Z" 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 21 00:31:20.997142 containerd[1458]: time="2026-10-21T00:31:20.997083312Z" level=info msg="Start subscribing containerd event" Oct 21 00:31:20.997346 containerd[1458]: time="2026-10-21T00:31:20.997282879Z" level=info msg="Start recovering state" Oct 21 00:31:20.997381 containerd[1458]: time="2026-10-21T00:31:20.997365389Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 21 00:31:20.997434 containerd[1458]: time="2026-10-21T00:31:20.997399567Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 21 00:31:20.997499 containerd[1458]: time="2026-10-21T00:31:20.997482583Z" level=info msg="Start event monitor" Oct 21 00:31:20.997517 containerd[1458]: time="2026-10-21T00:31:20.997506934Z" level=info msg="Start snapshots syncer" Oct 21 00:31:20.997517 containerd[1458]: time="2026-10-21T00:31:20.997515205Z" level=info msg="Start cni network conf syncer for default" Oct 21 00:31:20.997547 containerd[1458]: time="2026-10-21T00:31:20.997537432Z" level=info msg="Start streaming server" Oct 21 00:31:20.997678 containerd[1458]: time="2026-10-21T00:31:20.997595499Z" level=info msg="containerd successfully booted in 0.027871s" Oct 21 00:31:20.997941 systemd[1]: Started containerd.service - containerd container runtime. Oct 21 00:31:21.000147 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 21 00:31:21.008501 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 21 00:31:21.010814 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 21 00:31:21.012467 systemd[1]: Reached target getty.target - Login Prompts. Oct 21 00:31:22.369465 systemd-networkd[1398]: eth0: Gained IPv6LL Oct 21 00:31:22.372278 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 21 00:31:22.375122 systemd[1]: Reached target network-online.target - Network is Online. Oct 21 00:31:22.387522 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 21 00:31:22.390038 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 21 00:31:22.392483 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 21 00:31:22.407908 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 21 00:31:22.408576 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 21 00:31:22.410632 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 21 00:31:22.412744 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 21 00:31:22.987974 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 21 00:31:22.989964 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 21 00:31:22.994139 systemd[1]: Startup finished in 946ms (kernel) + 4.023s (initrd) + 4.238s (userspace) = 9.207s. Oct 21 00:31:22.994148 (kubelet)[1537]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 21 00:31:23.325830 kubelet[1537]: E1021 00:31:23.325703 1537 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 21 00:31:23.327737 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 21 00:31:23.327855 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 21 00:31:27.151720 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 21 00:31:27.152698 systemd[1]: Started sshd@0-10.0.0.136:22-10.0.0.1:34130.service - OpenSSH per-connection server daemon (10.0.0.1:34130). Oct 21 00:31:27.192172 sshd[1550]: Accepted publickey for core from 10.0.0.1 port 34130 ssh2: RSA SHA256:Md2SFI+pjM6v9z09sCdHJzjVbAXeq2x74fSZ+awtmdg Oct 21 00:31:27.193561 sshd[1550]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 21 00:31:27.201655 systemd-logind[1441]: New session 1 of user core. Oct 21 00:31:27.202454 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 21 00:31:27.211451 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 21 00:31:27.220282 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 21 00:31:27.221987 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 21 00:31:27.227616 (systemd)[1554]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 21 00:31:27.290104 systemd[1554]: Queued start job for default target default.target. Oct 21 00:31:27.305009 systemd[1554]: Created slice app.slice - User Application Slice. Oct 21 00:31:27.305044 systemd[1554]: Reached target paths.target - Paths. Oct 21 00:31:27.305053 systemd[1554]: Reached target timers.target - Timers. Oct 21 00:31:27.306038 systemd[1554]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 21 00:31:27.316423 systemd[1554]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 21 00:31:27.316555 systemd[1554]: Reached target sockets.target - Sockets. Oct 21 00:31:27.316571 systemd[1554]: Reached target basic.target - Basic System. Oct 21 00:31:27.316606 systemd[1554]: Reached target default.target - Main User Target. Oct 21 00:31:27.316634 systemd[1554]: Startup finished in 84ms. Oct 21 00:31:27.316839 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 21 00:31:27.318246 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 21 00:31:27.376528 systemd[1]: Started sshd@1-10.0.0.136:22-10.0.0.1:34146.service - OpenSSH per-connection server daemon (10.0.0.1:34146). Oct 21 00:31:27.408128 sshd[1565]: Accepted publickey for core from 10.0.0.1 port 34146 ssh2: RSA SHA256:Md2SFI+pjM6v9z09sCdHJzjVbAXeq2x74fSZ+awtmdg Oct 21 00:31:27.409108 sshd[1565]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 21 00:31:27.413401 systemd-logind[1441]: New session 2 of user core. Oct 21 00:31:27.419585 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 21 00:31:27.472178 sshd[1565]: pam_unix(sshd:session): session closed for user core Oct 21 00:31:27.483352 systemd[1]: sshd@1-10.0.0.136:22-10.0.0.1:34146.service: Deactivated successfully. Oct 21 00:31:27.484549 systemd[1]: session-2.scope: Deactivated successfully. Oct 21 00:31:27.485543 systemd-logind[1441]: Session 2 logged out. Waiting for processes to exit. Oct 21 00:31:27.486451 systemd[1]: Started sshd@2-10.0.0.136:22-10.0.0.1:34152.service - OpenSSH per-connection server daemon (10.0.0.1:34152). Oct 21 00:31:27.487020 systemd-logind[1441]: Removed session 2. Oct 21 00:31:27.516482 sshd[1572]: Accepted publickey for core from 10.0.0.1 port 34152 ssh2: RSA SHA256:Md2SFI+pjM6v9z09sCdHJzjVbAXeq2x74fSZ+awtmdg Oct 21 00:31:27.517779 sshd[1572]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 21 00:31:27.521498 systemd-logind[1441]: New session 3 of user core. Oct 21 00:31:27.537669 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 21 00:31:27.587503 sshd[1572]: pam_unix(sshd:session): session closed for user core Oct 21 00:31:27.601975 systemd[1]: sshd@2-10.0.0.136:22-10.0.0.1:34152.service: Deactivated successfully. Oct 21 00:31:27.603633 systemd[1]: session-3.scope: Deactivated successfully. Oct 21 00:31:27.605055 systemd-logind[1441]: Session 3 logged out. Waiting for processes to exit. Oct 21 00:31:27.606115 systemd[1]: Started sshd@3-10.0.0.136:22-10.0.0.1:34156.service - OpenSSH per-connection server daemon (10.0.0.1:34156). Oct 21 00:31:27.607014 systemd-logind[1441]: Removed session 3. Oct 21 00:31:27.638024 sshd[1579]: Accepted publickey for core from 10.0.0.1 port 34156 ssh2: RSA SHA256:Md2SFI+pjM6v9z09sCdHJzjVbAXeq2x74fSZ+awtmdg Oct 21 00:31:27.640171 sshd[1579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 21 00:31:27.645679 systemd-logind[1441]: New session 4 of user core. Oct 21 00:31:27.655635 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 21 00:31:27.709667 sshd[1579]: pam_unix(sshd:session): session closed for user core Oct 21 00:31:27.725675 systemd[1]: sshd@3-10.0.0.136:22-10.0.0.1:34156.service: Deactivated successfully. Oct 21 00:31:27.727030 systemd[1]: session-4.scope: Deactivated successfully. Oct 21 00:31:27.727996 systemd-logind[1441]: Session 4 logged out. Waiting for processes to exit. Oct 21 00:31:27.728934 systemd[1]: Started sshd@4-10.0.0.136:22-10.0.0.1:34158.service - OpenSSH per-connection server daemon (10.0.0.1:34158). Oct 21 00:31:27.729480 systemd-logind[1441]: Removed session 4. Oct 21 00:31:27.756824 sshd[1586]: Accepted publickey for core from 10.0.0.1 port 34158 ssh2: RSA SHA256:Md2SFI+pjM6v9z09sCdHJzjVbAXeq2x74fSZ+awtmdg Oct 21 00:31:27.757869 sshd[1586]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 21 00:31:27.761722 systemd-logind[1441]: New session 5 of user core. Oct 21 00:31:27.774617 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 21 00:31:27.832339 sudo[1589]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 21 00:31:27.832562 sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 21 00:31:27.855757 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 21 00:31:27.871816 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 21 00:31:27.872058 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 21 00:31:28.461066 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 21 00:31:28.471574 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 21 00:31:28.489154 systemd[1]: Reloading requested from client PID 1637 ('systemctl') (unit session-5.scope)... Oct 21 00:31:28.489175 systemd[1]: Reloading... Oct 21 00:31:28.544249 zram_generator::config[1675]: No configuration found. Oct 21 00:31:28.687732 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 21 00:31:28.733558 systemd[1]: Reloading finished in 244 ms. Oct 21 00:31:28.770410 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 21 00:31:28.772118 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Oct 21 00:31:28.773929 systemd[1]: kubelet.service: Deactivated successfully. Oct 21 00:31:28.774103 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 21 00:31:28.775379 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 21 00:31:28.867866 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 21 00:31:28.871136 (kubelet)[1725]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 21 00:31:28.958421 kubelet[1725]: 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 21 00:31:29.096474 kubelet[1725]: I1021 00:31:29.096300 1725 server.go:545] "Kubelet version" kubeletVersion="v1.36.1" Oct 21 00:31:29.096474 kubelet[1725]: I1021 00:31:29.096381 1725 server.go:547] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 21 00:31:29.097409 kubelet[1725]: I1021 00:31:29.097359 1725 watchdog_linux.go:94] "Systemd watchdog is not enabled" Oct 21 00:31:29.097409 kubelet[1725]: I1021 00:31:29.097411 1725 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 21 00:31:29.097783 kubelet[1725]: I1021 00:31:29.097758 1725 server.go:985] "Client rotation is on, will bootstrap in background" Oct 21 00:31:29.102417 kubelet[1725]: I1021 00:31:29.102329 1725 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 21 00:31:29.107010 kubelet[1725]: E1021 00:31:29.106929 1725 remote_runtime.go:1177] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Oct 21 00:31:29.107128 kubelet[1725]: I1021 00:31:29.107029 1725 server.go:1425] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Oct 21 00:31:29.114469 kubelet[1725]: I1021 00:31:29.114392 1725 server.go:808] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Oct 21 00:31:29.117394 kubelet[1725]: I1021 00:31:29.117148 1725 container_manager_linux.go:273] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 21 00:31:29.117527 kubelet[1725]: I1021 00:31:29.117391 1725 container_manager_linux.go:278] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.136","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"MemoryReservationPolicy":"None","PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 21 00:31:29.117725 kubelet[1725]: I1021 00:31:29.117530 1725 topology_manager.go:172] "Creating topology manager with none policy" Oct 21 00:31:29.117725 kubelet[1725]: I1021 00:31:29.117538 1725 container_manager_linux.go:309] "Creating device plugin manager" Oct 21 00:31:29.117784 kubelet[1725]: I1021 00:31:29.117724 1725 container_manager_linux.go:318] "Creating Dynamic Resource Allocation (DRA) manager" Oct 21 00:31:29.153543 kubelet[1725]: I1021 00:31:29.153460 1725 state_mem.go:45] "Initialized" logger="CPUManager state memory" Oct 21 00:31:29.153696 kubelet[1725]: I1021 00:31:29.153677 1725 kubelet.go:485] "Attempting to sync node with API server" Oct 21 00:31:29.153696 kubelet[1725]: I1021 00:31:29.153688 1725 kubelet.go:386] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 21 00:31:29.153752 kubelet[1725]: I1021 00:31:29.153701 1725 kubelet.go:397] "Adding apiserver pod source" Oct 21 00:31:29.153752 kubelet[1725]: I1021 00:31:29.153709 1725 apiserver.go:41] "Waiting for node sync before watching apiserver pods" Oct 21 00:31:29.153834 kubelet[1725]: E1021 00:31:29.153779 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:29.153899 kubelet[1725]: E1021 00:31:29.153853 1725 file.go:97] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:29.155831 kubelet[1725]: I1021 00:31:29.155803 1725 kuberuntime_manager.go:308] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Oct 21 00:31:29.156949 kubelet[1725]: I1021 00:31:29.156905 1725 kubelet.go:1002] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 21 00:31:29.156949 kubelet[1725]: I1021 00:31:29.156932 1725 kubelet.go:1029] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Oct 21 00:31:29.157042 kubelet[1725]: W1021 00:31:29.156976 1725 probe.go:275] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 21 00:31:29.159814 kubelet[1725]: I1021 00:31:29.159778 1725 server.go:1285] "Started kubelet" Oct 21 00:31:29.160446 kubelet[1725]: I1021 00:31:29.160016 1725 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 21 00:31:29.160446 kubelet[1725]: I1021 00:31:29.160081 1725 server_v1.go:49] "podresources" method="list" useActivePods=true Oct 21 00:31:29.160674 kubelet[1725]: I1021 00:31:29.160609 1725 server.go:193] "Starting to listen" address="0.0.0.0" port=10250 Oct 21 00:31:29.160844 kubelet[1725]: I1021 00:31:29.160813 1725 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer" Oct 21 00:31:29.161011 kubelet[1725]: I1021 00:31:29.160991 1725 server.go:264] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 21 00:31:29.161259 kubelet[1725]: I1021 00:31:29.161240 1725 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 21 00:31:29.161360 kubelet[1725]: I1021 00:31:29.161346 1725 tlsconfig.go:243] "Starting DynamicServingCertificateController" Oct 21 00:31:29.161701 kubelet[1725]: I1021 00:31:29.161685 1725 server.go:354] "Adding debug handlers to kubelet server" Oct 21 00:31:29.162328 kubelet[1725]: I1021 00:31:29.162281 1725 volume_manager.go:310] "Starting Kubelet Volume Manager" Oct 21 00:31:29.162470 kubelet[1725]: E1021 00:31:29.162371 1725 kubelet_node_status.go:396] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Oct 21 00:31:29.162662 kubelet[1725]: E1021 00:31:29.162598 1725 kubelet.go:3516] "Unable to register mirror pod because node is not registered yet" err="node \"10.0.0.136\" not found" node="10.0.0.136" Oct 21 00:31:29.163099 kubelet[1725]: I1021 00:31:29.162878 1725 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Oct 21 00:31:29.163099 kubelet[1725]: I1021 00:31:29.162981 1725 reconciler.go:29] "Reconciler: start to sync state" Oct 21 00:31:29.163399 kubelet[1725]: I1021 00:31:29.163292 1725 factory.go:222] Registration of the systemd container factory successfully Oct 21 00:31:29.163399 kubelet[1725]: I1021 00:31:29.163354 1725 factory.go:220] 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 21 00:31:29.166417 kubelet[1725]: I1021 00:31:29.165867 1725 factory.go:222] Registration of the containerd container factory successfully Oct 21 00:31:29.176398 kubelet[1725]: E1021 00:31:29.176361 1725 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.136\" not found" node="10.0.0.136" Oct 21 00:31:29.179841 kubelet[1725]: I1021 00:31:29.179799 1725 cpu_manager.go:235] "Starting" policy="none" Oct 21 00:31:29.179841 kubelet[1725]: I1021 00:31:29.179817 1725 cpu_manager.go:236] "Reconciling" reconcilePeriod="10s" Oct 21 00:31:29.179841 kubelet[1725]: I1021 00:31:29.179839 1725 state_mem.go:45] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory" Oct 21 00:31:29.182508 kubelet[1725]: I1021 00:31:29.182459 1725 policy_none.go:50] "Start" Oct 21 00:31:29.182508 kubelet[1725]: I1021 00:31:29.182490 1725 memory_manager.go:190] "Starting memorymanager" policy="None" Oct 21 00:31:29.182508 kubelet[1725]: I1021 00:31:29.182500 1725 state_mem.go:40] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Oct 21 00:31:29.184372 kubelet[1725]: I1021 00:31:29.184318 1725 policy_none.go:44] "Start" Oct 21 00:31:29.188479 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 21 00:31:29.197535 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 21 00:31:29.200954 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 21 00:31:29.211306 kubelet[1725]: E1021 00:31:29.211271 1725 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 21 00:31:29.211500 kubelet[1725]: I1021 00:31:29.211484 1725 eviction_manager.go:194] "Eviction manager: starting control loop" Oct 21 00:31:29.211534 kubelet[1725]: I1021 00:31:29.211503 1725 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 21 00:31:29.212010 kubelet[1725]: I1021 00:31:29.211828 1725 plugin_manager.go:121] "Starting Kubelet Plugin Manager" Oct 21 00:31:29.212762 kubelet[1725]: E1021 00:31:29.212743 1725 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 21 00:31:29.212762 kubelet[1725]: E1021 00:31:29.212774 1725 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.136\" not found" Oct 21 00:31:29.232100 kubelet[1725]: I1021 00:31:29.232022 1725 kubelet_network_linux.go:53] "Initialized iptables rules." protocol="IPv4" Oct 21 00:31:29.233207 kubelet[1725]: I1021 00:31:29.233181 1725 kubelet_network_linux.go:53] "Initialized iptables rules." protocol="IPv6" Oct 21 00:31:29.233207 kubelet[1725]: I1021 00:31:29.233247 1725 status_manager.go:277] "Starting to sync pod status with apiserver" Oct 21 00:31:29.233344 kubelet[1725]: I1021 00:31:29.233300 1725 kubelet.go:2622] "Starting kubelet main sync loop" Oct 21 00:31:29.233360 kubelet[1725]: E1021 00:31:29.233352 1725 kubelet.go:2646] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Oct 21 00:31:29.262859 kubelet[1725]: E1021 00:31:29.262693 1725 kubelet.go:3516] "Unable to register mirror pod because node is not registered yet" err="node \"10.0.0.136\" not found" node="10.0.0.136" Oct 21 00:31:29.314780 kubelet[1725]: I1021 00:31:29.314733 1725 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.136" Oct 21 00:31:29.334719 kubelet[1725]: I1021 00:31:29.334623 1725 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.136" Oct 21 00:31:29.378814 sudo[1589]: pam_unix(sudo:session): session closed for user root Oct 21 00:31:29.380835 sshd[1586]: pam_unix(sshd:session): session closed for user core Oct 21 00:31:29.383401 systemd[1]: sshd@4-10.0.0.136:22-10.0.0.1:34158.service: Deactivated successfully. Oct 21 00:31:29.384632 systemd[1]: session-5.scope: Deactivated successfully. Oct 21 00:31:29.385172 systemd-logind[1441]: Session 5 logged out. Waiting for processes to exit. Oct 21 00:31:29.385868 systemd-logind[1441]: Removed session 5. Oct 21 00:31:29.448550 kubelet[1725]: I1021 00:31:29.448490 1725 kuberuntime_manager.go:2146] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 21 00:31:29.448862 containerd[1458]: time="2026-10-21T00:31:29.448826019Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 21 00:31:29.449384 kubelet[1725]: I1021 00:31:29.448994 1725 kubelet_network.go:48] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 21 00:31:30.100522 kubelet[1725]: I1021 00:31:30.100436 1725 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 21 00:31:30.100932 kubelet[1725]: I1021 00:31:30.100798 1725 reflector.go:664] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:177" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:177: Unexpected watch close - watch lasted less than a second and no items received" Oct 21 00:31:30.100932 kubelet[1725]: I1021 00:31:30.100857 1725 reflector.go:664] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:177" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:177: Unexpected watch close - watch lasted less than a second and no items received" Oct 21 00:31:30.101033 kubelet[1725]: I1021 00:31:30.101010 1725 reflector.go:664] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:177" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:177: Unexpected watch close - watch lasted less than a second and no items received" Oct 21 00:31:30.154958 kubelet[1725]: I1021 00:31:30.154774 1725 apiserver.go:51] "Watching apiserver" Oct 21 00:31:30.155148 kubelet[1725]: E1021 00:31:30.154947 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:30.182322 systemd[1]: Created slice kubepods-burstable-podc870e760_7c09_4c9a_8cdf_0fb920b1c466.slice - libcontainer container kubepods-burstable-podc870e760_7c09_4c9a_8cdf_0fb920b1c466.slice. Oct 21 00:31:30.213881 systemd[1]: Created slice kubepods-besteffort-pod09e8474c_78d0_497c_a732_317ad0d0ded7.slice - libcontainer container kubepods-besteffort-pod09e8474c_78d0_497c_a732_317ad0d0ded7.slice. Oct 21 00:31:30.263837 kubelet[1725]: I1021 00:31:30.263702 1725 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Oct 21 00:31:30.269655 kubelet[1725]: I1021 00:31:30.269617 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/09e8474c-78d0-497c-a732-317ad0d0ded7-kube-proxy\") pod \"kube-proxy-wbtgr\" (UID: \"09e8474c-78d0-497c-a732-317ad0d0ded7\") " pod="kube-system/kube-proxy-wbtgr" Oct 21 00:31:30.269655 kubelet[1725]: I1021 00:31:30.269658 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/09e8474c-78d0-497c-a732-317ad0d0ded7-xtables-lock\") pod \"kube-proxy-wbtgr\" (UID: \"09e8474c-78d0-497c-a732-317ad0d0ded7\") " pod="kube-system/kube-proxy-wbtgr" Oct 21 00:31:30.269655 kubelet[1725]: I1021 00:31:30.269678 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/09e8474c-78d0-497c-a732-317ad0d0ded7-lib-modules\") pod \"kube-proxy-wbtgr\" (UID: \"09e8474c-78d0-497c-a732-317ad0d0ded7\") " pod="kube-system/kube-proxy-wbtgr" Oct 21 00:31:30.269794 kubelet[1725]: I1021 00:31:30.269695 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fpzcc\" (UniqueName: \"kubernetes.io/projected/09e8474c-78d0-497c-a732-317ad0d0ded7-kube-api-access-fpzcc\") pod \"kube-proxy-wbtgr\" (UID: \"09e8474c-78d0-497c-a732-317ad0d0ded7\") " pod="kube-system/kube-proxy-wbtgr" Oct 21 00:31:30.269794 kubelet[1725]: I1021 00:31:30.269716 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/c870e760-7c09-4c9a-8cdf-0fb920b1c466-run\") pod \"kube-flannel-ds-hgwcd\" (UID: \"c870e760-7c09-4c9a-8cdf-0fb920b1c466\") " pod="kube-flannel/kube-flannel-ds-hgwcd" Oct 21 00:31:30.269794 kubelet[1725]: I1021 00:31:30.269757 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/c870e760-7c09-4c9a-8cdf-0fb920b1c466-flannel-cfg\") pod \"kube-flannel-ds-hgwcd\" (UID: \"c870e760-7c09-4c9a-8cdf-0fb920b1c466\") " pod="kube-flannel/kube-flannel-ds-hgwcd" Oct 21 00:31:30.269794 kubelet[1725]: I1021 00:31:30.269779 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wvx8g\" (UniqueName: \"kubernetes.io/projected/c870e760-7c09-4c9a-8cdf-0fb920b1c466-kube-api-access-wvx8g\") pod \"kube-flannel-ds-hgwcd\" (UID: \"c870e760-7c09-4c9a-8cdf-0fb920b1c466\") " pod="kube-flannel/kube-flannel-ds-hgwcd" Oct 21 00:31:30.269857 kubelet[1725]: I1021 00:31:30.269796 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/c870e760-7c09-4c9a-8cdf-0fb920b1c466-cni-plugin\") pod \"kube-flannel-ds-hgwcd\" (UID: \"c870e760-7c09-4c9a-8cdf-0fb920b1c466\") " pod="kube-flannel/kube-flannel-ds-hgwcd" Oct 21 00:31:30.269857 kubelet[1725]: I1021 00:31:30.269809 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/c870e760-7c09-4c9a-8cdf-0fb920b1c466-cni\") pod \"kube-flannel-ds-hgwcd\" (UID: \"c870e760-7c09-4c9a-8cdf-0fb920b1c466\") " pod="kube-flannel/kube-flannel-ds-hgwcd" Oct 21 00:31:30.269857 kubelet[1725]: I1021 00:31:30.269837 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c870e760-7c09-4c9a-8cdf-0fb920b1c466-xtables-lock\") pod \"kube-flannel-ds-hgwcd\" (UID: \"c870e760-7c09-4c9a-8cdf-0fb920b1c466\") " pod="kube-flannel/kube-flannel-ds-hgwcd" Oct 21 00:31:30.516868 kubelet[1725]: E1021 00:31:30.516789 1725 dns.go:154] "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 21 00:31:30.518341 containerd[1458]: time="2026-10-21T00:31:30.518289145Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-hgwcd,Uid:c870e760-7c09-4c9a-8cdf-0fb920b1c466,Namespace:kube-flannel,Attempt:0,}" Oct 21 00:31:30.529463 kubelet[1725]: E1021 00:31:30.529403 1725 dns.go:154] "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 21 00:31:30.530361 containerd[1458]: time="2026-10-21T00:31:30.530300247Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wbtgr,Uid:09e8474c-78d0-497c-a732-317ad0d0ded7,Namespace:kube-system,Attempt:0,}" Oct 21 00:31:30.948995 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2222963825.mount: Deactivated successfully. Oct 21 00:31:30.954360 containerd[1458]: time="2026-10-21T00:31:30.954302738Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 21 00:31:30.955190 containerd[1458]: time="2026-10-21T00:31:30.955153042Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 21 00:31:30.955710 containerd[1458]: time="2026-10-21T00:31:30.955632691Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Oct 21 00:31:30.956257 containerd[1458]: time="2026-10-21T00:31:30.956230620Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312116" Oct 21 00:31:30.956871 containerd[1458]: time="2026-10-21T00:31:30.956831962Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 21 00:31:30.958917 containerd[1458]: time="2026-10-21T00:31:30.958890306Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 21 00:31:30.959624 containerd[1458]: time="2026-10-21T00:31:30.959583826Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 441.203231ms" Oct 21 00:31:30.961418 containerd[1458]: time="2026-10-21T00:31:30.961377421Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 431.015973ms" Oct 21 00:31:31.173434 kubelet[1725]: E1021 00:31:31.173357 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:31.184006 containerd[1458]: time="2026-10-21T00:31:31.183702687Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 21 00:31:31.184006 containerd[1458]: time="2026-10-21T00:31:31.183757511Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 21 00:31:31.184006 containerd[1458]: time="2026-10-21T00:31:31.183769286Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:31.184006 containerd[1458]: time="2026-10-21T00:31:31.183859772Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:31.207524 containerd[1458]: time="2026-10-21T00:31:31.207291004Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 21 00:31:31.207524 containerd[1458]: time="2026-10-21T00:31:31.207336348Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 21 00:31:31.207524 containerd[1458]: time="2026-10-21T00:31:31.207348208Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:31.207524 containerd[1458]: time="2026-10-21T00:31:31.207423704Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:31.286464 systemd[1]: Started cri-containerd-88d83898bc024ef6594ef72dd6a60a7606889a08bc3f4c3161d4d26a98be9806.scope - libcontainer container 88d83898bc024ef6594ef72dd6a60a7606889a08bc3f4c3161d4d26a98be9806. Oct 21 00:31:31.289080 systemd[1]: Started cri-containerd-698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf.scope - libcontainer container 698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf. Oct 21 00:31:31.604596 containerd[1458]: time="2026-10-21T00:31:31.604471431Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wbtgr,Uid:09e8474c-78d0-497c-a732-317ad0d0ded7,Namespace:kube-system,Attempt:0,} returns sandbox id \"88d83898bc024ef6594ef72dd6a60a7606889a08bc3f4c3161d4d26a98be9806\"" Oct 21 00:31:31.606423 kubelet[1725]: E1021 00:31:31.606367 1725 dns.go:154] "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 21 00:31:31.607594 containerd[1458]: time="2026-10-21T00:31:31.607564565Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.36.5\"" Oct 21 00:31:31.614762 containerd[1458]: time="2026-10-21T00:31:31.614728785Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-hgwcd,Uid:c870e760-7c09-4c9a-8cdf-0fb920b1c466,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf\"" Oct 21 00:31:31.615731 kubelet[1725]: E1021 00:31:31.615682 1725 dns.go:154] "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 21 00:31:32.174327 kubelet[1725]: E1021 00:31:32.174270 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:32.378209 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount159946678.mount: Deactivated successfully. Oct 21 00:31:32.758780 containerd[1458]: time="2026-10-21T00:31:32.758727116Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.36.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:32.759207 containerd[1458]: time="2026-10-21T00:31:32.759174094Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.36.5: active requests=0, bytes read=30077363" Oct 21 00:31:32.760283 containerd[1458]: time="2026-10-21T00:31:32.760143839Z" level=info msg="ImageCreate event name:\"sha256:a83c93aedd6acfab8933b1e95ad2f47ba024acb3f247919688f075cc992e23e3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:32.762510 containerd[1458]: time="2026-10-21T00:31:32.762464370Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:5f180e85f05b5b0949fc9d2886ca536c2aa8b8a3b8302b58e7d49c4a645f97a9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:32.763139 containerd[1458]: time="2026-10-21T00:31:32.763018604Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.36.5\" with image id \"sha256:a83c93aedd6acfab8933b1e95ad2f47ba024acb3f247919688f075cc992e23e3\", repo tag \"registry.k8s.io/kube-proxy:v1.36.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:5f180e85f05b5b0949fc9d2886ca536c2aa8b8a3b8302b58e7d49c4a645f97a9\", size \"30076143\" in 1.155418525s" Oct 21 00:31:32.763290 containerd[1458]: time="2026-10-21T00:31:32.763185399Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.36.5\" returns image reference \"sha256:a83c93aedd6acfab8933b1e95ad2f47ba024acb3f247919688f075cc992e23e3\"" Oct 21 00:31:32.764752 containerd[1458]: time="2026-10-21T00:31:32.764722921Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 21 00:31:32.768006 containerd[1458]: time="2026-10-21T00:31:32.767964973Z" level=info msg="CreateContainer within sandbox \"88d83898bc024ef6594ef72dd6a60a7606889a08bc3f4c3161d4d26a98be9806\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 21 00:31:32.780240 containerd[1458]: time="2026-10-21T00:31:32.780181357Z" level=info msg="CreateContainer within sandbox \"88d83898bc024ef6594ef72dd6a60a7606889a08bc3f4c3161d4d26a98be9806\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"d0d129c87867a63fd15a04b0c6470e58b7fb012088469a774c69e9d5b27167f2\"" Oct 21 00:31:32.781177 containerd[1458]: time="2026-10-21T00:31:32.781066974Z" level=info msg="StartContainer for \"d0d129c87867a63fd15a04b0c6470e58b7fb012088469a774c69e9d5b27167f2\"" Oct 21 00:31:32.841593 systemd[1]: Started cri-containerd-d0d129c87867a63fd15a04b0c6470e58b7fb012088469a774c69e9d5b27167f2.scope - libcontainer container d0d129c87867a63fd15a04b0c6470e58b7fb012088469a774c69e9d5b27167f2. Oct 21 00:31:32.877253 containerd[1458]: time="2026-10-21T00:31:32.877184898Z" level=info msg="StartContainer for \"d0d129c87867a63fd15a04b0c6470e58b7fb012088469a774c69e9d5b27167f2\" returns successfully" Oct 21 00:31:32.881081 kubelet[1725]: E1021 00:31:32.880914 1725 dns.go:154] "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 21 00:31:33.175726 kubelet[1725]: E1021 00:31:33.175529 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:33.256908 kubelet[1725]: E1021 00:31:33.256844 1725 dns.go:154] "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 21 00:31:34.166679 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1914527708.mount: Deactivated successfully. Oct 21 00:31:34.176432 kubelet[1725]: E1021 00:31:34.176377 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:34.203615 containerd[1458]: time="2026-10-21T00:31:34.203535458Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:34.203997 containerd[1458]: time="2026-10-21T00:31:34.203943782Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Oct 21 00:31:34.205200 containerd[1458]: time="2026-10-21T00:31:34.205138697Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:34.207918 containerd[1458]: time="2026-10-21T00:31:34.207853850Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:34.208898 containerd[1458]: time="2026-10-21T00:31:34.208862184Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 1.444115168s" Oct 21 00:31:34.208898 containerd[1458]: time="2026-10-21T00:31:34.208894812Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Oct 21 00:31:34.213174 containerd[1458]: time="2026-10-21T00:31:34.213130614Z" level=info msg="CreateContainer within sandbox \"698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 21 00:31:34.225799 containerd[1458]: time="2026-10-21T00:31:34.225752989Z" level=info msg="CreateContainer within sandbox \"698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7\"" Oct 21 00:31:34.226673 containerd[1458]: time="2026-10-21T00:31:34.226621245Z" level=info msg="StartContainer for \"cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7\"" Oct 21 00:31:34.285686 systemd[1]: Started cri-containerd-cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7.scope - libcontainer container cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7. Oct 21 00:31:34.357369 containerd[1458]: time="2026-10-21T00:31:34.357278467Z" level=info msg="StartContainer for \"cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7\" returns successfully" Oct 21 00:31:34.362531 kubelet[1725]: E1021 00:31:34.362260 1725 dns.go:154] "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 21 00:31:34.366472 systemd[1]: cri-containerd-cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7.scope: Deactivated successfully. Oct 21 00:31:34.397593 kubelet[1725]: I1021 00:31:34.396810 1725 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="kube-system/kube-proxy-wbtgr" podStartSLOduration=4.239186085 podStartE2EDuration="5.396597022s" totalImagesPullingTime="1.157410937s" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-10-21 00:31:29 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-10-21 00:31:32.898867338 +0000 UTC m=+4.024184834" watchObservedRunningTime="2026-10-21 00:31:34.396597022 +0000 UTC m=+5.521914517" Oct 21 00:31:34.538688 containerd[1458]: time="2026-10-21T00:31:34.538582894Z" level=info msg="shim disconnected" id=cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7 namespace=k8s.io Oct 21 00:31:34.538688 containerd[1458]: time="2026-10-21T00:31:34.538673221Z" level=warning msg="cleaning up after shim disconnected" id=cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7 namespace=k8s.io Oct 21 00:31:34.538688 containerd[1458]: time="2026-10-21T00:31:34.538686211Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 21 00:31:35.056480 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-cd10b1be730ca8f70fb1a3cf8be2dc5f62a29b1bddfa30bf0edc2a3fe88c4bc7-rootfs.mount: Deactivated successfully. Oct 21 00:31:35.177706 kubelet[1725]: E1021 00:31:35.177642 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:35.267702 kubelet[1725]: E1021 00:31:35.267658 1725 dns.go:154] "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 21 00:31:35.268884 containerd[1458]: time="2026-10-21T00:31:35.268849253Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 21 00:31:36.178838 kubelet[1725]: E1021 00:31:36.178762 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:37.236939 kubelet[1725]: E1021 00:31:37.236516 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:37.456080 containerd[1458]: time="2026-10-21T00:31:37.456039101Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:37.456520 containerd[1458]: time="2026-10-21T00:31:37.456474350Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Oct 21 00:31:37.457592 containerd[1458]: time="2026-10-21T00:31:37.457565461Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:37.460818 containerd[1458]: time="2026-10-21T00:31:37.460791769Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:37.462025 containerd[1458]: time="2026-10-21T00:31:37.461980230Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32996046\" in 2.193087828s" Oct 21 00:31:37.462088 containerd[1458]: time="2026-10-21T00:31:37.462022903Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Oct 21 00:31:37.465366 containerd[1458]: time="2026-10-21T00:31:37.465340234Z" level=info msg="CreateContainer within sandbox \"698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 21 00:31:37.473545 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount606612397.mount: Deactivated successfully. Oct 21 00:31:37.477024 containerd[1458]: time="2026-10-21T00:31:37.476977846Z" level=info msg="CreateContainer within sandbox \"698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6\"" Oct 21 00:31:37.478265 containerd[1458]: time="2026-10-21T00:31:37.477694674Z" level=info msg="StartContainer for \"1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6\"" Oct 21 00:31:37.558380 systemd[1]: Started cri-containerd-1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6.scope - libcontainer container 1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6. Oct 21 00:31:37.581322 systemd[1]: cri-containerd-1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6.scope: Deactivated successfully. Oct 21 00:31:37.583473 containerd[1458]: time="2026-10-21T00:31:37.583379019Z" level=info msg="StartContainer for \"1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6\" returns successfully" Oct 21 00:31:37.586512 kubelet[1725]: E1021 00:31:37.586489 1725 dns.go:154] "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 21 00:31:37.604631 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6-rootfs.mount: Deactivated successfully. Oct 21 00:31:37.647460 kubelet[1725]: I1021 00:31:37.647384 1725 kubelet_node_status.go:431] "Fast updating node status as it just became ready" Oct 21 00:31:37.668276 containerd[1458]: time="2026-10-21T00:31:37.668112783Z" level=info msg="shim disconnected" id=1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6 namespace=k8s.io Oct 21 00:31:37.668276 containerd[1458]: time="2026-10-21T00:31:37.668267949Z" level=warning msg="cleaning up after shim disconnected" id=1a8e017be2aa0d0ee42a9ac000e65c10d89a87bcdf7267ecc9bd61bf2475baa6 namespace=k8s.io Oct 21 00:31:37.668276 containerd[1458]: time="2026-10-21T00:31:37.668276022Z" level=info msg="cleaning up dead shim" namespace=k8s.io Oct 21 00:31:38.237508 kubelet[1725]: E1021 00:31:38.237194 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:38.294513 kubelet[1725]: E1021 00:31:38.294462 1725 dns.go:154] "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 21 00:31:38.302874 containerd[1458]: time="2026-10-21T00:31:38.302611325Z" level=info msg="CreateContainer within sandbox \"698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 21 00:31:38.318338 containerd[1458]: time="2026-10-21T00:31:38.318188018Z" level=info msg="CreateContainer within sandbox \"698884931e10686587f8a1adf1ea3bb7022938f0f7953d794e82ad01c35bfbcf\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"fe904c156eaa6a9ce3d13ff260622834f4b77c78baa8fd97854fd6803a8cf2a8\"" Oct 21 00:31:38.319005 containerd[1458]: time="2026-10-21T00:31:38.318964298Z" level=info msg="StartContainer for \"fe904c156eaa6a9ce3d13ff260622834f4b77c78baa8fd97854fd6803a8cf2a8\"" Oct 21 00:31:38.356428 systemd[1]: Started cri-containerd-fe904c156eaa6a9ce3d13ff260622834f4b77c78baa8fd97854fd6803a8cf2a8.scope - libcontainer container fe904c156eaa6a9ce3d13ff260622834f4b77c78baa8fd97854fd6803a8cf2a8. Oct 21 00:31:38.378001 containerd[1458]: time="2026-10-21T00:31:38.377966561Z" level=info msg="StartContainer for \"fe904c156eaa6a9ce3d13ff260622834f4b77c78baa8fd97854fd6803a8cf2a8\" returns successfully" Oct 21 00:31:38.382027 kubelet[1725]: E1021 00:31:38.381906 1725 dns.go:154] "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 21 00:31:38.396270 kubelet[1725]: I1021 00:31:38.396170 1725 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-hgwcd" podStartSLOduration=4.60845864 podStartE2EDuration="9.396148231s" totalImagesPullingTime="4.787689591s" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-10-21 00:31:29 +0000 UTC" imagePullSessionsCount=2 imagePullSessionsStartsCount=0 observedRunningTime="2026-10-21 00:31:38.396078176 +0000 UTC m=+9.521395677" watchObservedRunningTime="2026-10-21 00:31:38.396148231 +0000 UTC m=+9.521465721" Oct 21 00:31:39.237870 kubelet[1725]: E1021 00:31:39.237787 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:39.296512 kubelet[1725]: E1021 00:31:39.296464 1725 dns.go:154] "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 21 00:31:39.450445 systemd-networkd[1398]: flannel.1: Link UP Oct 21 00:31:39.450763 systemd-networkd[1398]: flannel.1: Gained carrier Oct 21 00:31:40.238966 kubelet[1725]: E1021 00:31:40.238870 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:40.280739 systemd[1]: Created slice kubepods-besteffort-pod68d41bcf_6dee_4dfe_a33c_c5eedaafc030.slice - libcontainer container kubepods-besteffort-pod68d41bcf_6dee_4dfe_a33c_c5eedaafc030.slice. Oct 21 00:31:40.460951 kubelet[1725]: I1021 00:31:40.460826 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bq4hc\" (UniqueName: \"kubernetes.io/projected/68d41bcf-6dee-4dfe-a33c-c5eedaafc030-kube-api-access-bq4hc\") pod \"nginx-deployment-85fb466b-b75mc\" (UID: \"68d41bcf-6dee-4dfe-a33c-c5eedaafc030\") " pod="default/nginx-deployment-85fb466b-b75mc" Oct 21 00:31:40.585845 containerd[1458]: time="2026-10-21T00:31:40.585741734Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85fb466b-b75mc,Uid:68d41bcf-6dee-4dfe-a33c-c5eedaafc030,Namespace:default,Attempt:0,}" Oct 21 00:31:40.604071 systemd-networkd[1398]: cni0: Link UP Oct 21 00:31:40.604329 systemd-networkd[1398]: cni0: Gained carrier Oct 21 00:31:40.605682 systemd-networkd[1398]: cni0: Lost carrier Oct 21 00:31:40.610294 systemd-networkd[1398]: flannel.1: Gained IPv6LL Oct 21 00:31:40.614379 systemd-networkd[1398]: vetha19d63eb: Link UP Oct 21 00:31:40.616501 kernel: cni0: port 1(vetha19d63eb) entered blocking state Oct 21 00:31:40.616543 kernel: cni0: port 1(vetha19d63eb) entered disabled state Oct 21 00:31:40.617403 kernel: vetha19d63eb: entered allmulticast mode Oct 21 00:31:40.618270 kernel: vetha19d63eb: entered promiscuous mode Oct 21 00:31:40.618294 kernel: cni0: port 1(vetha19d63eb) entered blocking state Oct 21 00:31:40.620001 kernel: cni0: port 1(vetha19d63eb) entered forwarding state Oct 21 00:31:40.620260 kernel: cni0: port 1(vetha19d63eb) entered disabled state Oct 21 00:31:40.627450 kernel: cni0: port 1(vetha19d63eb) entered blocking state Oct 21 00:31:40.627529 kernel: cni0: port 1(vetha19d63eb) entered forwarding state Oct 21 00:31:40.627538 systemd-networkd[1398]: vetha19d63eb: Gained carrier Oct 21 00:31:40.627686 systemd-networkd[1398]: cni0: Gained carrier Oct 21 00:31:40.629992 containerd[1458]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Oct 21 00:31:40.629992 containerd[1458]: delegateAdd: netconf sent to delegate plugin: Oct 21 00:31:40.652998 containerd[1458]: {"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="2026-10-21T00:31:40.652873710Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 21 00:31:40.652998 containerd[1458]: time="2026-10-21T00:31:40.652917619Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 21 00:31:40.652998 containerd[1458]: time="2026-10-21T00:31:40.652938858Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:40.654116 containerd[1458]: time="2026-10-21T00:31:40.653608724Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:40.669410 systemd[1]: Started cri-containerd-0d54bdde8f8c9ea7b2c89e0f76878205523a882370de7fb6b7f19a254ea79781.scope - libcontainer container 0d54bdde8f8c9ea7b2c89e0f76878205523a882370de7fb6b7f19a254ea79781. Oct 21 00:31:40.684859 systemd-resolved[1330]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 21 00:31:40.720666 containerd[1458]: time="2026-10-21T00:31:40.720631997Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85fb466b-b75mc,Uid:68d41bcf-6dee-4dfe-a33c-c5eedaafc030,Namespace:default,Attempt:0,} returns sandbox id \"0d54bdde8f8c9ea7b2c89e0f76878205523a882370de7fb6b7f19a254ea79781\"" Oct 21 00:31:40.721840 containerd[1458]: time="2026-10-21T00:31:40.721813533Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 21 00:31:41.239597 kubelet[1725]: E1021 00:31:41.239531 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:42.081415 systemd-networkd[1398]: vetha19d63eb: Gained IPv6LL Oct 21 00:31:42.240005 kubelet[1725]: E1021 00:31:42.239953 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:42.529578 systemd-networkd[1398]: cni0: Gained IPv6LL Oct 21 00:31:43.291784 kubelet[1725]: E1021 00:31:43.291728 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:43.416881 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2127754887.mount: Deactivated successfully. Oct 21 00:31:44.199767 containerd[1458]: time="2026-10-21T00:31:44.199702963Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:44.200086 containerd[1458]: time="2026-10-21T00:31:44.199950535Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=64611197" Oct 21 00:31:44.200706 containerd[1458]: time="2026-10-21T00:31:44.200672922Z" level=info msg="ImageCreate event name:\"sha256:8f59ff5046a70e421d35ed7d1e862a963215c9689b36cb8f64a6c42f9cf2955d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:44.202641 containerd[1458]: time="2026-10-21T00:31:44.202611374Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:50dc40e9ed93d19d1cf11bdcc014f8db4f7cc6a927f22d7114bb6ddf4d19f92d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:44.205454 containerd[1458]: time="2026-10-21T00:31:44.203687861Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8f59ff5046a70e421d35ed7d1e862a963215c9689b36cb8f64a6c42f9cf2955d\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:50dc40e9ed93d19d1cf11bdcc014f8db4f7cc6a927f22d7114bb6ddf4d19f92d\", size \"64611075\" in 3.481844687s" Oct 21 00:31:44.205454 containerd[1458]: time="2026-10-21T00:31:44.203734490Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8f59ff5046a70e421d35ed7d1e862a963215c9689b36cb8f64a6c42f9cf2955d\"" Oct 21 00:31:44.209397 containerd[1458]: time="2026-10-21T00:31:44.209345307Z" level=info msg="CreateContainer within sandbox \"0d54bdde8f8c9ea7b2c89e0f76878205523a882370de7fb6b7f19a254ea79781\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 21 00:31:44.219733 containerd[1458]: time="2026-10-21T00:31:44.219696509Z" level=info msg="CreateContainer within sandbox \"0d54bdde8f8c9ea7b2c89e0f76878205523a882370de7fb6b7f19a254ea79781\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"62318d485cf675b341061b3acf384ec347447280743b882e6f5eb655f1072462\"" Oct 21 00:31:44.220283 containerd[1458]: time="2026-10-21T00:31:44.220259006Z" level=info msg="StartContainer for \"62318d485cf675b341061b3acf384ec347447280743b882e6f5eb655f1072462\"" Oct 21 00:31:44.261477 systemd[1]: Started cri-containerd-62318d485cf675b341061b3acf384ec347447280743b882e6f5eb655f1072462.scope - libcontainer container 62318d485cf675b341061b3acf384ec347447280743b882e6f5eb655f1072462. Oct 21 00:31:44.292513 kubelet[1725]: E1021 00:31:44.292484 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:44.297542 containerd[1458]: time="2026-10-21T00:31:44.297508948Z" level=info msg="StartContainer for \"62318d485cf675b341061b3acf384ec347447280743b882e6f5eb655f1072462\" returns successfully" Oct 21 00:31:44.312685 kubelet[1725]: I1021 00:31:44.312602 1725 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="default/nginx-deployment-85fb466b-b75mc" podStartSLOduration=0.828107684 podStartE2EDuration="4.312581854s" totalImagesPullingTime="3.48447417s" totalInitContainerRuntime="0s" isStatefulPod=false podCreationTimestamp="2026-10-21 00:31:40 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-10-21 00:31:44.3125643 +0000 UTC m=+15.437881793" watchObservedRunningTime="2026-10-21 00:31:44.312581854 +0000 UTC m=+15.437899358" Oct 21 00:31:45.293589 kubelet[1725]: E1021 00:31:45.293497 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:46.294284 kubelet[1725]: E1021 00:31:46.294158 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:46.455709 systemd[1]: Created slice kubepods-besteffort-podfe9c77ae_d77a_47da_95ae_5c30d7cf284f.slice - libcontainer container kubepods-besteffort-podfe9c77ae_d77a_47da_95ae_5c30d7cf284f.slice. Oct 21 00:31:46.540748 kubelet[1725]: I1021 00:31:46.540693 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/fe9c77ae-d77a-47da-95ae-5c30d7cf284f-data\") pod \"nfs-server-provisioner-0\" (UID: \"fe9c77ae-d77a-47da-95ae-5c30d7cf284f\") " pod="default/nfs-server-provisioner-0" Oct 21 00:31:46.540748 kubelet[1725]: I1021 00:31:46.540741 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rkws5\" (UniqueName: \"kubernetes.io/projected/fe9c77ae-d77a-47da-95ae-5c30d7cf284f-kube-api-access-rkws5\") pod \"nfs-server-provisioner-0\" (UID: \"fe9c77ae-d77a-47da-95ae-5c30d7cf284f\") " pod="default/nfs-server-provisioner-0" Oct 21 00:31:46.761525 containerd[1458]: time="2026-10-21T00:31:46.761475811Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fe9c77ae-d77a-47da-95ae-5c30d7cf284f,Namespace:default,Attempt:0,}" Oct 21 00:31:46.780693 systemd-networkd[1398]: vethc2004ca1: Link UP Oct 21 00:31:46.782892 kernel: cni0: port 2(vethc2004ca1) entered blocking state Oct 21 00:31:46.782936 kernel: cni0: port 2(vethc2004ca1) entered disabled state Oct 21 00:31:46.783793 kernel: vethc2004ca1: entered allmulticast mode Oct 21 00:31:46.784618 kernel: vethc2004ca1: entered promiscuous mode Oct 21 00:31:46.785692 kernel: cni0: port 2(vethc2004ca1) entered blocking state Oct 21 00:31:46.785715 kernel: cni0: port 2(vethc2004ca1) entered forwarding state Oct 21 00:31:46.787244 kernel: cni0: port 2(vethc2004ca1) entered disabled state Oct 21 00:31:46.795370 kernel: cni0: port 2(vethc2004ca1) entered blocking state Oct 21 00:31:46.795436 kernel: cni0: port 2(vethc2004ca1) entered forwarding state Oct 21 00:31:46.795437 systemd-networkd[1398]: vethc2004ca1: Gained carrier Oct 21 00:31:46.799261 containerd[1458]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Oct 21 00:31:46.799261 containerd[1458]: delegateAdd: netconf sent to delegate plugin: Oct 21 00:31:46.824736 containerd[1458]: {"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="2026-10-21T00:31:46.824291257Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 21 00:31:46.824736 containerd[1458]: time="2026-10-21T00:31:46.824591436Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 21 00:31:46.824736 containerd[1458]: time="2026-10-21T00:31:46.824606565Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:46.824736 containerd[1458]: time="2026-10-21T00:31:46.824689595Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:46.848391 systemd[1]: Started cri-containerd-a5b62136df20a1bc773ec19d9a9f611d2092b74ed9f8010fb43e53402a98be5e.scope - libcontainer container a5b62136df20a1bc773ec19d9a9f611d2092b74ed9f8010fb43e53402a98be5e. Oct 21 00:31:46.858918 systemd-resolved[1330]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 21 00:31:46.884839 containerd[1458]: time="2026-10-21T00:31:46.884798043Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fe9c77ae-d77a-47da-95ae-5c30d7cf284f,Namespace:default,Attempt:0,} returns sandbox id \"a5b62136df20a1bc773ec19d9a9f611d2092b74ed9f8010fb43e53402a98be5e\"" Oct 21 00:31:46.887071 containerd[1458]: time="2026-10-21T00:31:46.886855109Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 21 00:31:47.294835 kubelet[1725]: E1021 00:31:47.294712 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:48.045067 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount100136853.mount: Deactivated successfully. Oct 21 00:31:48.226498 systemd-networkd[1398]: vethc2004ca1: Gained IPv6LL Oct 21 00:31:48.296308 kubelet[1725]: E1021 00:31:48.296144 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:49.154744 kubelet[1725]: E1021 00:31:49.154691 1725 file.go:103] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:49.191681 containerd[1458]: time="2026-10-21T00:31:49.191613553Z" 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 21 00:31:49.192049 containerd[1458]: time="2026-10-21T00:31:49.192013369Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039378" Oct 21 00:31:49.192940 containerd[1458]: time="2026-10-21T00:31:49.192907716Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:49.195061 containerd[1458]: time="2026-10-21T00:31:49.195032617Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:49.195943 containerd[1458]: time="2026-10-21T00:31:49.195910040Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 2.309021044s" Oct 21 00:31:49.195981 containerd[1458]: time="2026-10-21T00:31:49.195949184Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Oct 21 00:31:49.199425 containerd[1458]: time="2026-10-21T00:31:49.199397897Z" level=info msg="CreateContainer within sandbox \"a5b62136df20a1bc773ec19d9a9f611d2092b74ed9f8010fb43e53402a98be5e\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 21 00:31:49.210745 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount920777057.mount: Deactivated successfully. Oct 21 00:31:49.211365 containerd[1458]: time="2026-10-21T00:31:49.211339553Z" level=info msg="CreateContainer within sandbox \"a5b62136df20a1bc773ec19d9a9f611d2092b74ed9f8010fb43e53402a98be5e\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"bf6eff5fd185097d79545e6a89417f30e3d0fc1be84b9dd00079743606e2ba7a\"" Oct 21 00:31:49.211990 containerd[1458]: time="2026-10-21T00:31:49.211951585Z" level=info msg="StartContainer for \"bf6eff5fd185097d79545e6a89417f30e3d0fc1be84b9dd00079743606e2ba7a\"" Oct 21 00:31:49.245383 systemd[1]: Started cri-containerd-bf6eff5fd185097d79545e6a89417f30e3d0fc1be84b9dd00079743606e2ba7a.scope - libcontainer container bf6eff5fd185097d79545e6a89417f30e3d0fc1be84b9dd00079743606e2ba7a. Oct 21 00:31:49.296798 kubelet[1725]: E1021 00:31:49.296770 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:49.310952 containerd[1458]: time="2026-10-21T00:31:49.310897646Z" level=info msg="StartContainer for \"bf6eff5fd185097d79545e6a89417f30e3d0fc1be84b9dd00079743606e2ba7a\" returns successfully" Oct 21 00:31:49.642666 kernel: hrtimer: interrupt took 9168740 ns Oct 21 00:31:50.297451 kubelet[1725]: E1021 00:31:50.297356 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:51.298553 kubelet[1725]: E1021 00:31:51.298458 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:52.299446 kubelet[1725]: E1021 00:31:52.299345 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:53.300148 kubelet[1725]: E1021 00:31:53.300055 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:54.300775 kubelet[1725]: E1021 00:31:54.300707 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:54.538511 kubelet[1725]: I1021 00:31:54.538417 1725 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.228194426 podStartE2EDuration="8.538394377s" totalImagesPullingTime="2.310199951s" totalInitContainerRuntime="0s" isStatefulPod=false podCreationTimestamp="2026-10-21 00:31:46 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-10-21 00:31:49.32965753 +0000 UTC m=+20.454975019" watchObservedRunningTime="2026-10-21 00:31:54.538394377 +0000 UTC m=+25.663711884" Oct 21 00:31:54.544899 systemd[1]: Created slice kubepods-besteffort-pod076b804e_e5e6_4082_916f_a109eb359bdb.slice - libcontainer container kubepods-besteffort-pod076b804e_e5e6_4082_916f_a109eb359bdb.slice. Oct 21 00:31:54.702833 kubelet[1725]: I1021 00:31:54.702731 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-336de4de-cd9c-45db-9e5a-bede49bd0779\" (UniqueName: \"kubernetes.io/nfs/076b804e-e5e6-4082-916f-a109eb359bdb-pvc-336de4de-cd9c-45db-9e5a-bede49bd0779\") pod \"test-pod-1\" (UID: \"076b804e-e5e6-4082-916f-a109eb359bdb\") " pod="default/test-pod-1" Oct 21 00:31:54.702833 kubelet[1725]: I1021 00:31:54.702793 1725 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sm7lq\" (UniqueName: \"kubernetes.io/projected/076b804e-e5e6-4082-916f-a109eb359bdb-kube-api-access-sm7lq\") pod \"test-pod-1\" (UID: \"076b804e-e5e6-4082-916f-a109eb359bdb\") " pod="default/test-pod-1" Oct 21 00:31:54.833263 kernel: FS-Cache: Loaded Oct 21 00:31:54.897794 kernel: RPC: Registered named UNIX socket transport module. Oct 21 00:31:54.897922 kernel: RPC: Registered udp transport module. Oct 21 00:31:54.897943 kernel: RPC: Registered tcp transport module. Oct 21 00:31:54.897955 kernel: RPC: Registered tcp-with-tls transport module. Oct 21 00:31:54.898604 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 21 00:31:55.113499 kernel: NFS: Registering the id_resolver key type Oct 21 00:31:55.114097 kernel: Key type id_resolver registered Oct 21 00:31:55.114430 kernel: Key type id_legacy registered Oct 21 00:31:55.164927 nfsidmap[2741]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 21 00:31:55.173102 nfsidmap[2744]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 21 00:31:55.301135 kubelet[1725]: E1021 00:31:55.301058 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:55.452608 containerd[1458]: time="2026-10-21T00:31:55.451883827Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:076b804e-e5e6-4082-916f-a109eb359bdb,Namespace:default,Attempt:0,}" Oct 21 00:31:55.504108 systemd-networkd[1398]: veth4ef54095: Link UP Oct 21 00:31:55.507270 kernel: cni0: port 3(veth4ef54095) entered blocking state Oct 21 00:31:55.507432 kernel: cni0: port 3(veth4ef54095) entered disabled state Oct 21 00:31:55.507455 kernel: veth4ef54095: entered allmulticast mode Oct 21 00:31:55.510239 kernel: veth4ef54095: entered promiscuous mode Oct 21 00:31:55.520327 kernel: cni0: port 3(veth4ef54095) entered blocking state Oct 21 00:31:55.520386 kernel: cni0: port 3(veth4ef54095) entered forwarding state Oct 21 00:31:55.520065 systemd-networkd[1398]: veth4ef54095: Gained carrier Oct 21 00:31:55.525490 containerd[1458]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Oct 21 00:31:55.525490 containerd[1458]: delegateAdd: netconf sent to delegate plugin: Oct 21 00:31:55.559035 containerd[1458]: {"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="2026-10-21T00:31:55.558755444Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 21 00:31:55.559035 containerd[1458]: time="2026-10-21T00:31:55.558815198Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 21 00:31:55.559035 containerd[1458]: time="2026-10-21T00:31:55.558830010Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:55.559035 containerd[1458]: time="2026-10-21T00:31:55.558926028Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 21 00:31:55.598501 systemd[1]: Started cri-containerd-5f80725ec3bf7d00897cba35bacbc52ed67e6093a8c205a4d8eeb71acc2f8fde.scope - libcontainer container 5f80725ec3bf7d00897cba35bacbc52ed67e6093a8c205a4d8eeb71acc2f8fde. Oct 21 00:31:55.615895 systemd-resolved[1330]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 21 00:31:55.662742 containerd[1458]: time="2026-10-21T00:31:55.662645982Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:076b804e-e5e6-4082-916f-a109eb359bdb,Namespace:default,Attempt:0,} returns sandbox id \"5f80725ec3bf7d00897cba35bacbc52ed67e6093a8c205a4d8eeb71acc2f8fde\"" Oct 21 00:31:55.666072 containerd[1458]: time="2026-10-21T00:31:55.666024482Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 21 00:31:56.089388 containerd[1458]: time="2026-10-21T00:31:56.087843076Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 21 00:31:56.092905 containerd[1458]: time="2026-10-21T00:31:56.092131969Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 21 00:31:56.097726 containerd[1458]: time="2026-10-21T00:31:56.097604967Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8f59ff5046a70e421d35ed7d1e862a963215c9689b36cb8f64a6c42f9cf2955d\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:50dc40e9ed93d19d1cf11bdcc014f8db4f7cc6a927f22d7114bb6ddf4d19f92d\", size \"64611075\" in 431.519195ms" Oct 21 00:31:56.101376 containerd[1458]: time="2026-10-21T00:31:56.097669617Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8f59ff5046a70e421d35ed7d1e862a963215c9689b36cb8f64a6c42f9cf2955d\"" Oct 21 00:31:56.120986 containerd[1458]: time="2026-10-21T00:31:56.113206294Z" level=info msg="CreateContainer within sandbox \"5f80725ec3bf7d00897cba35bacbc52ed67e6093a8c205a4d8eeb71acc2f8fde\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 21 00:31:56.149270 containerd[1458]: time="2026-10-21T00:31:56.149138771Z" level=info msg="CreateContainer within sandbox \"5f80725ec3bf7d00897cba35bacbc52ed67e6093a8c205a4d8eeb71acc2f8fde\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"10423cc84e2dc7a5f89d88c015ce7eb28e5c57d44e5bc0200efd5c3c4fa94764\"" Oct 21 00:31:56.150557 containerd[1458]: time="2026-10-21T00:31:56.150502686Z" level=info msg="StartContainer for \"10423cc84e2dc7a5f89d88c015ce7eb28e5c57d44e5bc0200efd5c3c4fa94764\"" Oct 21 00:31:56.218574 systemd[1]: Started cri-containerd-10423cc84e2dc7a5f89d88c015ce7eb28e5c57d44e5bc0200efd5c3c4fa94764.scope - libcontainer container 10423cc84e2dc7a5f89d88c015ce7eb28e5c57d44e5bc0200efd5c3c4fa94764. Oct 21 00:31:56.296605 containerd[1458]: time="2026-10-21T00:31:56.296542879Z" level=info msg="StartContainer for \"10423cc84e2dc7a5f89d88c015ce7eb28e5c57d44e5bc0200efd5c3c4fa94764\" returns successfully" Oct 21 00:31:56.301286 kubelet[1725]: E1021 00:31:56.301136 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:57.249552 systemd-networkd[1398]: veth4ef54095: Gained IPv6LL Oct 21 00:31:57.302363 kubelet[1725]: E1021 00:31:57.302052 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:58.303006 kubelet[1725]: E1021 00:31:58.302888 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 21 00:31:59.303278 kubelet[1725]: E1021 00:31:59.303242 1725 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"