Sep 4 00:54:21.933361 kernel: Linux version 6.6.156-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 Thu Sep 3 23:08:52 -00 2026 Sep 4 00:54:21.933382 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=6ebb2cdcffcb4a51d5b97f22d8b1daad1e78ad3b15bdcb193da070efafd84d0c Sep 4 00:54:21.933392 kernel: BIOS-provided physical RAM map: Sep 4 00:54:21.933397 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 4 00:54:21.933402 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 4 00:54:21.933408 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 4 00:54:21.933414 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Sep 4 00:54:21.933419 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Sep 4 00:54:21.933424 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 4 00:54:21.933429 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Sep 4 00:54:21.933435 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 4 00:54:21.933441 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 4 00:54:21.933446 kernel: NX (Execute Disable) protection: active Sep 4 00:54:21.933451 kernel: APIC: Static calls initialized Sep 4 00:54:21.933458 kernel: SMBIOS 2.8 present. Sep 4 00:54:21.933464 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Sep 4 00:54:21.933470 kernel: Hypervisor detected: KVM Sep 4 00:54:21.933476 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 4 00:54:21.933482 kernel: kvm-clock: using sched offset of 4386875169 cycles Sep 4 00:54:21.933488 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 4 00:54:21.933494 kernel: tsc: Detected 2793.438 MHz processor Sep 4 00:54:21.933499 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 4 00:54:21.933505 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 4 00:54:21.933511 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x10000000000 Sep 4 00:54:21.933517 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 4 00:54:21.933524 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 4 00:54:21.933529 kernel: Using GB pages for direct mapping Sep 4 00:54:21.933535 kernel: ACPI: Early table checksum verification disabled Sep 4 00:54:21.933541 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Sep 4 00:54:21.933546 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 00:54:21.933552 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 00:54:21.933558 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 00:54:21.933563 kernel: ACPI: FACS 0x000000009CFE0000 000040 Sep 4 00:54:21.933569 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 00:54:21.933576 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 00:54:21.933582 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 00:54:21.933609 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 00:54:21.933615 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Sep 4 00:54:21.933621 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Sep 4 00:54:21.933627 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Sep 4 00:54:21.933635 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Sep 4 00:54:21.933643 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Sep 4 00:54:21.933649 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Sep 4 00:54:21.933655 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Sep 4 00:54:21.933661 kernel: No NUMA configuration found Sep 4 00:54:21.933667 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Sep 4 00:54:21.933673 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] Sep 4 00:54:21.933679 kernel: Zone ranges: Sep 4 00:54:21.933686 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 4 00:54:21.933692 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Sep 4 00:54:21.933698 kernel: Normal empty Sep 4 00:54:21.933704 kernel: Movable zone start for each node Sep 4 00:54:21.933710 kernel: Early memory node ranges Sep 4 00:54:21.933716 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 4 00:54:21.933722 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Sep 4 00:54:21.933728 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Sep 4 00:54:21.933733 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 4 00:54:21.933741 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 4 00:54:21.933747 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Sep 4 00:54:21.933753 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 4 00:54:21.933759 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 4 00:54:21.933765 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 4 00:54:21.933771 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 4 00:54:21.933777 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 4 00:54:21.933782 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 4 00:54:21.933788 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 4 00:54:21.933796 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 4 00:54:21.933801 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 4 00:54:21.933807 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 4 00:54:21.933813 kernel: TSC deadline timer available Sep 4 00:54:21.933819 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Sep 4 00:54:21.933825 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 4 00:54:21.933830 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 4 00:54:21.933836 kernel: kvm-guest: setup PV sched yield Sep 4 00:54:21.933842 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Sep 4 00:54:21.933848 kernel: Booting paravirtualized kernel on KVM Sep 4 00:54:21.933856 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 4 00:54:21.933862 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 4 00:54:21.933869 kernel: percpu: Embedded 57 pages/cpu s196392 r8192 d28888 u524288 Sep 4 00:54:21.933874 kernel: pcpu-alloc: s196392 r8192 d28888 u524288 alloc=1*2097152 Sep 4 00:54:21.933880 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 4 00:54:21.933886 kernel: kvm-guest: PV spinlocks enabled Sep 4 00:54:21.933907 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 4 00:54:21.933918 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=6ebb2cdcffcb4a51d5b97f22d8b1daad1e78ad3b15bdcb193da070efafd84d0c Sep 4 00:54:21.933929 kernel: random: crng init done Sep 4 00:54:21.933938 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 00:54:21.933945 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 00:54:21.933950 kernel: Fallback order for Node 0: 0 Sep 4 00:54:21.933955 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 Sep 4 00:54:21.933960 kernel: Policy zone: DMA32 Sep 4 00:54:21.933965 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 00:54:21.933970 kernel: Memory: 2431600K/2571752K available (14336K kernel code, 2288K rwdata, 22772K rodata, 42920K init, 2236K bss, 139948K reserved, 0K cma-reserved) Sep 4 00:54:21.933975 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 4 00:54:21.933982 kernel: ftrace: allocating 37502 entries in 148 pages Sep 4 00:54:21.933987 kernel: ftrace: allocated 148 pages with 3 groups Sep 4 00:54:21.933991 kernel: Dynamic Preempt: voluntary Sep 4 00:54:21.933996 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 00:54:21.934004 kernel: rcu: RCU event tracing is enabled. Sep 4 00:54:21.934009 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 4 00:54:21.934014 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 00:54:21.934019 kernel: Rude variant of Tasks RCU enabled. Sep 4 00:54:21.934024 kernel: Tracing variant of Tasks RCU enabled. Sep 4 00:54:21.934031 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 00:54:21.934036 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 4 00:54:21.934040 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 4 00:54:21.934045 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 00:54:21.934051 kernel: Console: colour VGA+ 80x25 Sep 4 00:54:21.934056 kernel: printk: console [ttyS0] enabled Sep 4 00:54:21.934060 kernel: ACPI: Core revision 20230628 Sep 4 00:54:21.934066 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 4 00:54:21.934071 kernel: APIC: Switch to symmetric I/O mode setup Sep 4 00:54:21.934077 kernel: x2apic enabled Sep 4 00:54:21.934082 kernel: APIC: Switched APIC routing to: physical x2apic Sep 4 00:54:21.934087 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 4 00:54:21.934103 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 4 00:54:21.934109 kernel: kvm-guest: setup PV IPIs Sep 4 00:54:21.934114 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 4 00:54:21.934119 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 4 00:54:21.934131 kernel: Calibrating delay loop (skipped) preset value.. 5586.87 BogoMIPS (lpj=2793438) Sep 4 00:54:21.934137 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 4 00:54:21.934142 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 4 00:54:21.934148 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 4 00:54:21.934153 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 4 00:54:21.934160 kernel: Spectre V2 : Mitigation: Retpolines Sep 4 00:54:21.934165 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 4 00:54:21.934171 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Sep 4 00:54:21.934177 kernel: RETBleed: Vulnerable Sep 4 00:54:21.934184 kernel: Speculative Store Bypass: Vulnerable Sep 4 00:54:21.934189 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 4 00:54:21.934195 kernel: GDS: Unknown: Dependent on hypervisor status Sep 4 00:54:21.934200 kernel: active return thunk: its_return_thunk Sep 4 00:54:21.934205 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 4 00:54:21.934211 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 4 00:54:21.934216 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 4 00:54:21.934222 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 4 00:54:21.934228 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Sep 4 00:54:21.934234 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Sep 4 00:54:21.934240 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Sep 4 00:54:21.934245 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 4 00:54:21.934251 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Sep 4 00:54:21.934256 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Sep 4 00:54:21.934261 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Sep 4 00:54:21.934266 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Sep 4 00:54:21.934272 kernel: Freeing SMP alternatives memory: 32K Sep 4 00:54:21.934278 kernel: pid_max: default: 32768 minimum: 301 Sep 4 00:54:21.934284 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 4 00:54:21.934290 kernel: landlock: Up and running. Sep 4 00:54:21.934295 kernel: SELinux: Initializing. Sep 4 00:54:21.934300 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 00:54:21.934306 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 00:54:21.934312 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8370C CPU @ 2.80GHz (family: 0x6, model: 0x6a, stepping: 0x6) Sep 4 00:54:21.934317 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 00:54:21.934322 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 00:54:21.934328 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 00:54:21.934335 kernel: Performance Events: unsupported p6 CPU model 106 no PMU driver, software events only. Sep 4 00:54:21.934340 kernel: signal: max sigframe size: 3632 Sep 4 00:54:21.934346 kernel: rcu: Hierarchical SRCU implementation. Sep 4 00:54:21.934352 kernel: rcu: Max phase no-delay instances is 400. Sep 4 00:54:21.934357 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 4 00:54:21.934362 kernel: smp: Bringing up secondary CPUs ... Sep 4 00:54:21.934368 kernel: smpboot: x86: Booting SMP configuration: Sep 4 00:54:21.934373 kernel: .... node #0, CPUs: #1 #2 #3 Sep 4 00:54:21.934378 kernel: smp: Brought up 1 node, 4 CPUs Sep 4 00:54:21.934385 kernel: smpboot: Max logical packages: 1 Sep 4 00:54:21.934391 kernel: smpboot: Total of 4 processors activated (22347.50 BogoMIPS) Sep 4 00:54:21.934396 kernel: devtmpfs: initialized Sep 4 00:54:21.934401 kernel: x86/mm: Memory block size: 128MB Sep 4 00:54:21.934407 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 00:54:21.934412 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 4 00:54:21.934418 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 00:54:21.934423 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 00:54:21.934429 kernel: audit: initializing netlink subsys (disabled) Sep 4 00:54:21.934436 kernel: audit: type=2000 audit(1788483260.889:1): state=initialized audit_enabled=0 res=1 Sep 4 00:54:21.934441 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 00:54:21.934446 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 4 00:54:21.934452 kernel: cpuidle: using governor menu Sep 4 00:54:21.934457 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 00:54:21.934463 kernel: dca service started, version 1.12.1 Sep 4 00:54:21.934468 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Sep 4 00:54:21.934474 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 4 00:54:21.934479 kernel: PCI: Using configuration type 1 for base access Sep 4 00:54:21.934486 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 4 00:54:21.934491 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 00:54:21.934497 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 00:54:21.934502 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 00:54:21.934508 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 00:54:21.934513 kernel: ACPI: Added _OSI(Module Device) Sep 4 00:54:21.934518 kernel: ACPI: Added _OSI(Processor Device) Sep 4 00:54:21.934524 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 00:54:21.934529 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 00:54:21.934535 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 4 00:54:21.934541 kernel: ACPI: Interpreter enabled Sep 4 00:54:21.934546 kernel: ACPI: PM: (supports S0 S3 S5) Sep 4 00:54:21.934551 kernel: ACPI: Using IOAPIC for interrupt routing Sep 4 00:54:21.934557 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 4 00:54:21.934562 kernel: PCI: Using E820 reservations for host bridge windows Sep 4 00:54:21.934568 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 4 00:54:21.934573 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 4 00:54:21.934699 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 4 00:54:21.934764 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 4 00:54:21.934820 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 4 00:54:21.934827 kernel: PCI host bridge to bus 0000:00 Sep 4 00:54:21.934884 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 4 00:54:21.934976 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 4 00:54:21.935057 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 4 00:54:21.935159 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 4 00:54:21.935210 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 4 00:54:21.935259 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Sep 4 00:54:21.935308 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 4 00:54:21.935379 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Sep 4 00:54:21.935441 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Sep 4 00:54:21.935497 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Sep 4 00:54:21.935554 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Sep 4 00:54:21.935648 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Sep 4 00:54:21.935703 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 4 00:54:21.935765 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Sep 4 00:54:21.935821 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Sep 4 00:54:21.935875 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Sep 4 00:54:21.935963 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Sep 4 00:54:21.936029 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Sep 4 00:54:21.936086 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Sep 4 00:54:21.936166 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Sep 4 00:54:21.936308 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Sep 4 00:54:21.936446 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Sep 4 00:54:21.936503 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Sep 4 00:54:21.936560 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Sep 4 00:54:21.936659 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Sep 4 00:54:21.936756 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Sep 4 00:54:21.936887 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Sep 4 00:54:21.937014 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 4 00:54:21.937104 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Sep 4 00:54:21.937185 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Sep 4 00:54:21.937270 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Sep 4 00:54:21.937355 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Sep 4 00:54:21.937436 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Sep 4 00:54:21.937449 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 4 00:54:21.937460 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 4 00:54:21.937471 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 4 00:54:21.937481 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 4 00:54:21.937492 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 4 00:54:21.937505 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 4 00:54:21.937515 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 4 00:54:21.937526 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 4 00:54:21.937536 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 4 00:54:21.937547 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 4 00:54:21.937558 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 4 00:54:21.937568 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 4 00:54:21.937579 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 4 00:54:21.937689 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 4 00:54:21.937702 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 4 00:54:21.937713 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 4 00:54:21.937723 kernel: iommu: Default domain type: Translated Sep 4 00:54:21.937734 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 4 00:54:21.937744 kernel: PCI: Using ACPI for IRQ routing Sep 4 00:54:21.937755 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 4 00:54:21.937766 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 4 00:54:21.937776 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Sep 4 00:54:21.937864 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 4 00:54:21.937969 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 4 00:54:21.938135 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 4 00:54:21.938148 kernel: vgaarb: loaded Sep 4 00:54:21.938159 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 4 00:54:21.938170 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 4 00:54:21.938180 kernel: clocksource: Switched to clocksource kvm-clock Sep 4 00:54:21.938201 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 00:54:21.938212 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 00:54:21.938225 kernel: pnp: PnP ACPI init Sep 4 00:54:21.938317 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 4 00:54:21.938331 kernel: pnp: PnP ACPI: found 6 devices Sep 4 00:54:21.938342 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 4 00:54:21.938352 kernel: NET: Registered PF_INET protocol family Sep 4 00:54:21.938363 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 00:54:21.938374 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 00:54:21.938384 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 00:54:21.938395 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 00:54:21.938408 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 00:54:21.938418 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 00:54:21.938429 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 00:54:21.938440 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 00:54:21.938450 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 00:54:21.938461 kernel: NET: Registered PF_XDP protocol family Sep 4 00:54:21.938538 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 4 00:54:21.938858 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 4 00:54:21.939084 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 4 00:54:21.939162 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 4 00:54:21.939236 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 4 00:54:21.939312 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Sep 4 00:54:21.939325 kernel: PCI: CLS 0 bytes, default 64 Sep 4 00:54:21.939336 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 4 00:54:21.939347 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 4 00:54:21.939358 kernel: Initialise system trusted keyrings Sep 4 00:54:21.939369 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 00:54:21.939383 kernel: Key type asymmetric registered Sep 4 00:54:21.939394 kernel: Asymmetric key parser 'x509' registered Sep 4 00:54:21.939405 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 4 00:54:21.939416 kernel: io scheduler mq-deadline registered Sep 4 00:54:21.939427 kernel: io scheduler kyber registered Sep 4 00:54:21.939438 kernel: io scheduler bfq registered Sep 4 00:54:21.939449 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 4 00:54:21.939461 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 4 00:54:21.939473 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 4 00:54:21.939486 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 4 00:54:21.939496 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 00:54:21.939507 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 4 00:54:21.939517 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 4 00:54:21.939526 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 4 00:54:21.939533 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 4 00:54:21.939834 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 4 00:54:21.939845 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 4 00:54:21.939931 kernel: rtc_cmos 00:04: registered as rtc0 Sep 4 00:54:21.939984 kernel: rtc_cmos 00:04: setting system clock to 2026-09-04T00:54:21 UTC (1788483261) Sep 4 00:54:21.940034 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 4 00:54:21.940041 kernel: intel_pstate: CPU model not supported Sep 4 00:54:21.940046 kernel: NET: Registered PF_INET6 protocol family Sep 4 00:54:21.940052 kernel: Segment Routing with IPv6 Sep 4 00:54:21.940058 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 00:54:21.940064 kernel: NET: Registered PF_PACKET protocol family Sep 4 00:54:21.940069 kernel: Key type dns_resolver registered Sep 4 00:54:21.940077 kernel: IPI shorthand broadcast: enabled Sep 4 00:54:21.940083 kernel: sched_clock: Marking stable (864008278, 274425748)->(1230293400, -91859374) Sep 4 00:54:21.940089 kernel: registered taskstats version 1 Sep 4 00:54:21.940094 kernel: Loading compiled-in X.509 certificates Sep 4 00:54:21.940100 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.156-flatcar: 93a3faa2243c4c3971f1ae3a145d7605e98475a6' Sep 4 00:54:21.940106 kernel: Key type .fscrypt registered Sep 4 00:54:21.940111 kernel: Key type fscrypt-provisioning registered Sep 4 00:54:21.940117 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 00:54:21.940123 kernel: ima: Allocated hash algorithm: sha1 Sep 4 00:54:21.940130 kernel: ima: No architecture policies found Sep 4 00:54:21.940135 kernel: clk: Disabling unused clocks Sep 4 00:54:21.940140 kernel: Freeing unused kernel image (initmem) memory: 42920K Sep 4 00:54:21.940146 kernel: Write protecting the kernel read-only data: 38912k Sep 4 00:54:21.940152 kernel: Freeing unused kernel image (rodata/data gap) memory: 1804K Sep 4 00:54:21.940157 kernel: Run /init as init process Sep 4 00:54:21.940163 kernel: with arguments: Sep 4 00:54:21.940168 kernel: /init Sep 4 00:54:21.940174 kernel: with environment: Sep 4 00:54:21.940181 kernel: HOME=/ Sep 4 00:54:21.940187 kernel: TERM=linux Sep 4 00:54:21.940195 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) Sep 4 00:54:21.940202 systemd[1]: Detected virtualization kvm. Sep 4 00:54:21.940209 systemd[1]: Detected architecture x86-64. Sep 4 00:54:21.940215 systemd[1]: Running in initrd. Sep 4 00:54:21.940220 systemd[1]: No hostname configured, using default hostname. Sep 4 00:54:21.940228 systemd[1]: Hostname set to . Sep 4 00:54:21.940234 systemd[1]: Initializing machine ID from VM UUID. Sep 4 00:54:21.940240 systemd[1]: Queued start job for default target initrd.target. Sep 4 00:54:21.940246 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 00:54:21.940252 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 00:54:21.940258 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 00:54:21.940264 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 00:54:21.940270 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 00:54:21.940278 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 00:54:21.940294 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 00:54:21.940300 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 00:54:21.940307 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 00:54:21.940313 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 00:54:21.940320 systemd[1]: Reached target paths.target - Path Units. Sep 4 00:54:21.940327 systemd[1]: Reached target slices.target - Slice Units. Sep 4 00:54:21.940333 systemd[1]: Reached target swap.target - Swaps. Sep 4 00:54:21.940339 systemd[1]: Reached target timers.target - Timer Units. Sep 4 00:54:21.940345 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 00:54:21.940351 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 00:54:21.940357 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 00:54:21.940363 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 4 00:54:21.940369 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 00:54:21.940377 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 00:54:21.940383 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 00:54:21.940389 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 00:54:21.940396 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 00:54:21.940402 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 00:54:21.940408 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 00:54:21.940414 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 00:54:21.940420 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 00:54:21.940427 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 00:54:21.940434 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:54:21.940440 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 00:54:21.940446 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 00:54:21.940465 systemd-journald[193]: Collecting audit messages is disabled. Sep 4 00:54:21.940483 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 00:54:21.940492 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 00:54:21.940500 systemd-journald[193]: Journal started Sep 4 00:54:21.940516 systemd-journald[193]: Runtime Journal (/run/log/journal/f6f327158d7c429f9027319c6d75ccb7) is 6.0M, max 48.3M, 42.3M free. Sep 4 00:54:21.936970 systemd-modules-load[194]: Inserted module 'overlay' Sep 4 00:54:21.948660 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 00:54:21.951209 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 00:54:21.965696 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 00:54:21.967910 systemd-modules-load[194]: Inserted module 'br_netfilter' Sep 4 00:54:22.049133 kernel: Bridge firewalling registered Sep 4 00:54:21.969858 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 00:54:22.057436 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 00:54:22.062164 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 00:54:22.062443 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:54:22.069209 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 00:54:22.073775 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 00:54:22.074126 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 00:54:22.084834 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 00:54:22.086770 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 00:54:22.089204 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 00:54:22.102788 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 00:54:22.105791 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 00:54:22.118624 dracut-cmdline[228]: dracut-dracut-053 Sep 4 00:54:22.121277 dracut-cmdline[228]: 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=6ebb2cdcffcb4a51d5b97f22d8b1daad1e78ad3b15bdcb193da070efafd84d0c Sep 4 00:54:22.131959 systemd-resolved[229]: Positive Trust Anchors: Sep 4 00:54:22.131979 systemd-resolved[229]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 00:54:22.132005 systemd-resolved[229]: 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 Sep 4 00:54:22.134037 systemd-resolved[229]: Defaulting to hostname 'linux'. Sep 4 00:54:22.134782 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 00:54:22.138510 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 00:54:22.219659 kernel: SCSI subsystem initialized Sep 4 00:54:22.230656 kernel: Loading iSCSI transport class v2.0-870. Sep 4 00:54:22.241795 kernel: iscsi: registered transport (tcp) Sep 4 00:54:22.260678 kernel: iscsi: registered transport (qla4xxx) Sep 4 00:54:22.260928 kernel: QLogic iSCSI HBA Driver Sep 4 00:54:22.297202 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 00:54:22.311196 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 00:54:22.334706 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 00:54:22.334990 kernel: device-mapper: uevent: version 1.0.3 Sep 4 00:54:22.335011 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 00:54:22.376665 kernel: raid6: avx512x4 gen() 40721 MB/s Sep 4 00:54:22.393661 kernel: raid6: avx512x2 gen() 42687 MB/s Sep 4 00:54:22.411667 kernel: raid6: avx512x1 gen() 38843 MB/s Sep 4 00:54:22.430992 kernel: raid6: avx2x4 gen() 30730 MB/s Sep 4 00:54:22.447700 kernel: raid6: avx2x2 gen() 17649 MB/s Sep 4 00:54:22.465401 kernel: raid6: avx2x1 gen() 17607 MB/s Sep 4 00:54:22.465711 kernel: raid6: using algorithm avx512x2 gen() 42687 MB/s Sep 4 00:54:22.483981 kernel: raid6: .... xor() 29906 MB/s, rmw enabled Sep 4 00:54:22.484386 kernel: raid6: using avx512x2 recovery algorithm Sep 4 00:54:22.505657 kernel: xor: automatically using best checksumming function avx Sep 4 00:54:22.636667 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 00:54:22.647166 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 00:54:22.662098 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 00:54:22.676920 systemd-udevd[414]: Using default interface naming scheme 'v255'. Sep 4 00:54:22.683136 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 00:54:22.697988 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 00:54:22.710981 dracut-pre-trigger[425]: rd.md=0: removing MD RAID activation Sep 4 00:54:22.740975 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 00:54:22.759145 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 00:54:22.797523 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 00:54:22.805963 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 00:54:22.820209 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 00:54:22.824396 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 00:54:22.828000 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 00:54:22.830467 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 00:54:22.848625 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 4 00:54:22.850625 kernel: cryptd: max_cpu_qlen set to 1000 Sep 4 00:54:22.853162 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 00:54:22.859023 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 4 00:54:22.864077 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 00:54:22.876803 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 4 00:54:22.876836 kernel: GPT:9289727 != 19775487 Sep 4 00:54:22.876851 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 4 00:54:22.876865 kernel: GPT:9289727 != 19775487 Sep 4 00:54:22.876884 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 4 00:54:22.876911 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 00:54:22.877959 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 00:54:22.879650 kernel: libata version 3.00 loaded. Sep 4 00:54:22.879877 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 00:54:22.884126 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 00:54:22.892023 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 00:54:22.892159 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:54:22.901865 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (463) Sep 4 00:54:22.901886 kernel: AVX2 version of gcm_enc/dec engaged. Sep 4 00:54:22.896005 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:54:22.906533 kernel: BTRFS: device fsid 1ef81432-848a-41d8-ace8-c7648b316155 devid 1 transid 31 /dev/vda3 scanned by (udev-worker) (470) Sep 4 00:54:22.906553 kernel: AES CTR mode by8 optimization enabled Sep 4 00:54:22.908852 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:54:22.915313 kernel: ahci 0000:00:1f.2: version 3.0 Sep 4 00:54:22.915491 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 4 00:54:22.915507 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Sep 4 00:54:22.915772 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 4 00:54:22.922645 kernel: scsi host0: ahci Sep 4 00:54:22.924638 kernel: scsi host1: ahci Sep 4 00:54:22.926289 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 4 00:54:23.021504 kernel: scsi host2: ahci Sep 4 00:54:23.022048 kernel: scsi host3: ahci Sep 4 00:54:23.022304 kernel: scsi host4: ahci Sep 4 00:54:23.022386 kernel: scsi host5: ahci Sep 4 00:54:23.022471 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 Sep 4 00:54:23.022481 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 Sep 4 00:54:23.022498 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 Sep 4 00:54:23.022505 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 Sep 4 00:54:23.022512 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 Sep 4 00:54:23.022519 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 Sep 4 00:54:23.026162 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:54:23.036208 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 4 00:54:23.042295 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 00:54:23.047854 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 4 00:54:23.048000 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 4 00:54:23.069174 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 00:54:23.072521 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 00:54:23.078731 disk-uuid[556]: Primary Header is updated. Sep 4 00:54:23.078731 disk-uuid[556]: Secondary Entries is updated. Sep 4 00:54:23.078731 disk-uuid[556]: Secondary Header is updated. Sep 4 00:54:23.083819 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 00:54:23.088658 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 00:54:23.095686 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 00:54:23.105825 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 00:54:23.243647 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 4 00:54:23.244033 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 4 00:54:23.244050 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 4 00:54:23.244063 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 4 00:54:23.246649 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 4 00:54:23.246835 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 4 00:54:23.247755 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 4 00:54:23.248778 kernel: ata3.00: applying bridge limits Sep 4 00:54:23.249706 kernel: ata3.00: configured for UDMA/100 Sep 4 00:54:23.250625 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 4 00:54:23.302668 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 4 00:54:23.328801 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 4 00:54:23.339639 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 4 00:54:24.093646 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 00:54:24.094248 disk-uuid[557]: The operation has completed successfully. Sep 4 00:54:24.116526 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 00:54:24.116677 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 00:54:24.136039 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 00:54:24.141930 sh[594]: Success Sep 4 00:54:24.155673 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Sep 4 00:54:24.189008 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 00:54:24.203468 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 00:54:24.208206 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 00:54:24.221230 kernel: BTRFS info (device dm-0): first mount of filesystem 1ef81432-848a-41d8-ace8-c7648b316155 Sep 4 00:54:24.221390 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:54:24.221409 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 00:54:24.222508 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 00:54:24.223542 kernel: BTRFS info (device dm-0): using free space tree Sep 4 00:54:24.230209 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 00:54:24.230926 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 00:54:24.237934 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 00:54:24.240193 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 00:54:24.256204 kernel: BTRFS info (device vda6): first mount of filesystem 313e1a72-2636-4561-b2f6-02c8e4ad93ed Sep 4 00:54:24.256444 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:54:24.256454 kernel: BTRFS info (device vda6): using free space tree Sep 4 00:54:24.260783 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 00:54:24.268227 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 4 00:54:24.270928 kernel: BTRFS info (device vda6): last unmount of filesystem 313e1a72-2636-4561-b2f6-02c8e4ad93ed Sep 4 00:54:24.276108 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 00:54:24.284781 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 00:54:24.337798 ignition[692]: Ignition 2.19.0 Sep 4 00:54:24.337806 ignition[692]: Stage: fetch-offline Sep 4 00:54:24.337835 ignition[692]: no configs at "/usr/lib/ignition/base.d" Sep 4 00:54:24.337842 ignition[692]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 00:54:24.337943 ignition[692]: parsed url from cmdline: "" Sep 4 00:54:24.337946 ignition[692]: no config URL provided Sep 4 00:54:24.337949 ignition[692]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 00:54:24.337955 ignition[692]: no config at "/usr/lib/ignition/user.ign" Sep 4 00:54:24.337975 ignition[692]: op(1): [started] loading QEMU firmware config module Sep 4 00:54:24.350174 unknown[692]: fetched base config from "system" Sep 4 00:54:24.337978 ignition[692]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 4 00:54:24.350194 unknown[692]: fetched user config from "qemu" Sep 4 00:54:24.344121 ignition[692]: op(1): [finished] loading QEMU firmware config module Sep 4 00:54:24.354077 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 00:54:24.345799 ignition[692]: parsing config with SHA512: 61f03bf593342fcadb35b8652478e07bbd3c589b1b202061b1b0c172c7bb46dae73f59a08cc96815065b2c5949b985988aa8a4d4fd756b19d1f39da5e366fece Sep 4 00:54:24.357719 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 00:54:24.350537 ignition[692]: fetch-offline: fetch-offline passed Sep 4 00:54:24.350661 ignition[692]: Ignition finished successfully Sep 4 00:54:24.368986 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 00:54:24.389111 systemd-networkd[783]: lo: Link UP Sep 4 00:54:24.389239 systemd-networkd[783]: lo: Gained carrier Sep 4 00:54:24.390352 systemd-networkd[783]: Enumeration completed Sep 4 00:54:24.390946 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:54:24.390948 systemd-networkd[783]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 00:54:24.391864 systemd-networkd[783]: eth0: Link UP Sep 4 00:54:24.391866 systemd-networkd[783]: eth0: Gained carrier Sep 4 00:54:24.391873 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:54:24.392093 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 00:54:24.393327 systemd[1]: Reached target network.target - Network. Sep 4 00:54:24.399676 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 4 00:54:24.408883 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 00:54:24.418720 systemd-networkd[783]: eth0: DHCPv4 address 10.0.0.50/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 00:54:24.425451 ignition[785]: Ignition 2.19.0 Sep 4 00:54:24.425465 ignition[785]: Stage: kargs Sep 4 00:54:24.425628 ignition[785]: no configs at "/usr/lib/ignition/base.d" Sep 4 00:54:24.425636 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 00:54:24.428568 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 00:54:24.426291 ignition[785]: kargs: kargs passed Sep 4 00:54:24.426327 ignition[785]: Ignition finished successfully Sep 4 00:54:24.442149 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 00:54:24.457990 ignition[794]: Ignition 2.19.0 Sep 4 00:54:24.458001 ignition[794]: Stage: disks Sep 4 00:54:24.461934 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 00:54:24.458198 ignition[794]: no configs at "/usr/lib/ignition/base.d" Sep 4 00:54:24.463477 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 00:54:24.458208 ignition[794]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 00:54:24.466147 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 00:54:24.459220 ignition[794]: disks: disks passed Sep 4 00:54:24.468091 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 00:54:24.459276 ignition[794]: Ignition finished successfully Sep 4 00:54:24.470605 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 00:54:24.473646 systemd[1]: Reached target basic.target - Basic System. Sep 4 00:54:24.490231 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 00:54:24.501155 systemd-fsck[805]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 4 00:54:24.505924 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 00:54:24.518956 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 00:54:24.610624 kernel: EXT4-fs (vda9): mounted filesystem c07bbc38-1b98-47ee-b889-a56689a665ae r/w with ordered data mode. Quota mode: none. Sep 4 00:54:24.611212 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 00:54:24.612165 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 00:54:24.625931 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 00:54:24.627021 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 00:54:24.629836 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 4 00:54:24.638377 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (814) Sep 4 00:54:24.638398 kernel: BTRFS info (device vda6): first mount of filesystem 313e1a72-2636-4561-b2f6-02c8e4ad93ed Sep 4 00:54:24.629868 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 00:54:24.647154 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:54:24.647183 kernel: BTRFS info (device vda6): using free space tree Sep 4 00:54:24.629886 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 00:54:24.639809 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 00:54:24.643925 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 00:54:24.655618 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 00:54:24.656023 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 00:54:24.683692 initrd-setup-root[838]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 00:54:24.689105 initrd-setup-root[845]: cut: /sysroot/etc/group: No such file or directory Sep 4 00:54:24.696664 initrd-setup-root[852]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 00:54:24.702582 initrd-setup-root[859]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 00:54:24.801611 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 00:54:24.815023 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 00:54:24.819025 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 00:54:24.827648 kernel: BTRFS info (device vda6): last unmount of filesystem 313e1a72-2636-4561-b2f6-02c8e4ad93ed Sep 4 00:54:24.844112 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 00:54:24.851899 ignition[928]: INFO : Ignition 2.19.0 Sep 4 00:54:24.851899 ignition[928]: INFO : Stage: mount Sep 4 00:54:24.854699 ignition[928]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 00:54:24.854699 ignition[928]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 00:54:24.854699 ignition[928]: INFO : mount: mount passed Sep 4 00:54:24.854699 ignition[928]: INFO : Ignition finished successfully Sep 4 00:54:24.859550 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 00:54:24.874155 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 00:54:25.219773 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 00:54:25.232143 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 00:54:25.242471 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (941) Sep 4 00:54:25.242650 kernel: BTRFS info (device vda6): first mount of filesystem 313e1a72-2636-4561-b2f6-02c8e4ad93ed Sep 4 00:54:25.242661 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 4 00:54:25.245704 kernel: BTRFS info (device vda6): using free space tree Sep 4 00:54:25.249617 kernel: BTRFS info (device vda6): auto enabling async discard Sep 4 00:54:25.250959 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 00:54:25.278218 ignition[958]: INFO : Ignition 2.19.0 Sep 4 00:54:25.278218 ignition[958]: INFO : Stage: files Sep 4 00:54:25.281052 ignition[958]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 00:54:25.281052 ignition[958]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 00:54:25.281052 ignition[958]: DEBUG : files: compiled without relabeling support, skipping Sep 4 00:54:25.281052 ignition[958]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 00:54:25.281052 ignition[958]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 00:54:25.290187 ignition[958]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 00:54:25.292494 ignition[958]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 00:54:25.294494 unknown[958]: wrote ssh authorized keys file for user: core Sep 4 00:54:25.296440 ignition[958]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Sep 4 00:54:25.298833 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.36.1-x86-64.raw: attempt #1 Sep 4 00:54:25.461692 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 4 00:54:25.776001 systemd-networkd[783]: eth0: Gained IPv6LL Sep 4 00:54:25.810302 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.36.1-x86-64.raw" Sep 4 00:54:25.810302 ignition[958]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 4 00:54:25.815689 ignition[958]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 00:54:25.815689 ignition[958]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 00:54:25.815689 ignition[958]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 4 00:54:25.815689 ignition[958]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 4 00:54:25.847126 ignition[958]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 00:54:25.853055 ignition[958]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 00:54:25.855994 ignition[958]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 4 00:54:25.855994 ignition[958]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 00:54:25.855994 ignition[958]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 00:54:25.855994 ignition[958]: INFO : files: files passed Sep 4 00:54:25.855994 ignition[958]: INFO : Ignition finished successfully Sep 4 00:54:25.864842 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 00:54:25.889244 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 00:54:25.893869 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 00:54:25.897047 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 00:54:25.897157 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 00:54:25.906507 initrd-setup-root-after-ignition[986]: grep: /sysroot/oem/oem-release: No such file or directory Sep 4 00:54:25.911185 initrd-setup-root-after-ignition[988]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 00:54:25.911185 initrd-setup-root-after-ignition[988]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 00:54:25.918566 initrd-setup-root-after-ignition[992]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 00:54:25.913866 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 00:54:25.917161 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 00:54:25.934102 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 00:54:25.965344 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 00:54:25.965697 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 00:54:25.967549 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 00:54:25.973229 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 00:54:25.974523 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 00:54:25.985278 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 00:54:25.998893 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 00:54:26.017199 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 00:54:26.026681 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 00:54:26.029185 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 00:54:26.033208 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 00:54:26.036792 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 00:54:26.036982 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 00:54:26.044172 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 00:54:26.046134 systemd[1]: Stopped target basic.target - Basic System. Sep 4 00:54:26.049375 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 00:54:26.052385 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 00:54:26.056988 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 00:54:26.060489 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 00:54:26.064342 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 00:54:26.068827 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 00:54:26.072123 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 00:54:26.076160 systemd[1]: Stopped target swap.target - Swaps. Sep 4 00:54:26.079776 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 00:54:26.079980 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 00:54:26.083478 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 00:54:26.086969 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 00:54:26.091086 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 00:54:26.092949 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 00:54:26.095444 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 00:54:26.095653 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 00:54:26.100846 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 00:54:26.101034 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 00:54:26.104728 systemd[1]: Stopped target paths.target - Path Units. Sep 4 00:54:26.107716 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 00:54:26.113220 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 00:54:26.116054 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 00:54:26.119241 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 00:54:26.123018 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 00:54:26.123141 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 00:54:26.124650 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 00:54:26.124747 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 00:54:26.129001 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 00:54:26.129109 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 00:54:26.132279 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 00:54:26.132368 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 00:54:26.146987 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 00:54:26.147431 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 00:54:26.147618 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 00:54:26.148559 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 00:54:26.148894 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 00:54:26.148999 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 00:54:26.149168 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 00:54:26.172221 ignition[1013]: INFO : Ignition 2.19.0 Sep 4 00:54:26.172221 ignition[1013]: INFO : Stage: umount Sep 4 00:54:26.172221 ignition[1013]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 00:54:26.172221 ignition[1013]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 00:54:26.149223 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 00:54:26.191110 ignition[1013]: INFO : umount: umount passed Sep 4 00:54:26.191110 ignition[1013]: INFO : Ignition finished successfully Sep 4 00:54:26.151855 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 00:54:26.151943 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 00:54:26.166236 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 00:54:26.174397 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 00:54:26.174472 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 00:54:26.176397 systemd[1]: Stopped target network.target - Network. Sep 4 00:54:26.176521 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 00:54:26.176574 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 00:54:26.183198 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 00:54:26.183274 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 00:54:26.187383 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 00:54:26.187439 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 00:54:26.191099 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 00:54:26.191144 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 00:54:26.194353 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 00:54:26.197437 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 00:54:26.201709 systemd-networkd[783]: eth0: DHCPv6 lease lost Sep 4 00:54:26.206048 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 00:54:26.206425 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 00:54:26.211693 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 00:54:26.211811 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 00:54:26.218103 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 00:54:26.218533 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 00:54:26.222646 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 00:54:26.222699 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 00:54:26.224957 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 00:54:26.225027 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 00:54:26.234857 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 00:54:26.235788 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 00:54:26.235850 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 00:54:26.238203 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 00:54:26.238243 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 00:54:26.243747 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 00:54:26.243819 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 00:54:26.247342 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 00:54:26.247561 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 00:54:26.251901 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 00:54:26.275004 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 00:54:26.275562 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 00:54:26.283266 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 00:54:26.283578 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 00:54:26.286958 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 00:54:26.287004 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 00:54:26.288213 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 00:54:26.288309 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 00:54:26.292791 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 00:54:26.292866 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 00:54:26.297118 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 00:54:26.297193 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 00:54:26.301978 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 00:54:26.302039 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 00:54:26.325281 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 00:54:26.327176 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 00:54:26.327289 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 00:54:26.331450 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 00:54:26.331526 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:54:26.334988 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 00:54:26.335092 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 00:54:26.338404 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 00:54:26.343062 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 00:54:26.357513 systemd[1]: Switching root. Sep 4 00:54:26.390949 systemd-journald[193]: Journal stopped Sep 4 00:54:27.222412 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). Sep 4 00:54:27.222478 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 00:54:27.222498 kernel: SELinux: policy capability open_perms=1 Sep 4 00:54:27.222514 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 00:54:27.222527 kernel: SELinux: policy capability always_check_network=0 Sep 4 00:54:27.222541 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 00:54:27.222558 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 00:54:27.222572 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 00:54:27.222586 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 00:54:27.222696 kernel: audit: type=1403 audit(1788483266.543:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 00:54:27.222712 systemd[1]: Successfully loaded SELinux policy in 76.654ms. Sep 4 00:54:27.222744 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 10.518ms. Sep 4 00:54:27.222760 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) Sep 4 00:54:27.222774 systemd[1]: Detected virtualization kvm. Sep 4 00:54:27.222789 systemd[1]: Detected architecture x86-64. Sep 4 00:54:27.222802 systemd[1]: Detected first boot. Sep 4 00:54:27.222816 systemd[1]: Initializing machine ID from VM UUID. Sep 4 00:54:27.222830 zram_generator::config[1057]: No configuration found. Sep 4 00:54:27.222848 systemd[1]: Populated /etc with preset unit settings. Sep 4 00:54:27.222863 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 00:54:27.222879 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 00:54:27.222894 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 00:54:27.222910 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 00:54:27.222945 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 00:54:27.222960 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 00:54:27.222976 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 00:54:27.222990 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 00:54:27.223005 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 00:54:27.223021 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 00:54:27.223034 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 00:54:27.223049 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 00:54:27.223064 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 00:54:27.223078 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 00:54:27.223092 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 00:54:27.223109 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 00:54:27.223124 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 00:54:27.223138 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 4 00:54:27.223152 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 00:54:27.223166 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 00:54:27.223180 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 00:54:27.223195 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 00:54:27.223211 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 00:54:27.223225 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 00:54:27.223240 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 00:54:27.223257 systemd[1]: Reached target slices.target - Slice Units. Sep 4 00:54:27.223271 systemd[1]: Reached target swap.target - Swaps. Sep 4 00:54:27.223285 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 00:54:27.223299 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 00:54:27.223314 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 00:54:27.223328 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 00:54:27.223343 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 00:54:27.223359 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 00:54:27.223373 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 00:54:27.223387 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 00:54:27.223402 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 00:54:27.223416 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:27.223430 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 00:54:27.223444 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 00:54:27.223458 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 00:54:27.223474 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). Sep 4 00:54:27.223489 systemd[1]: Reached target machines.target - Containers. Sep 4 00:54:27.223503 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 00:54:27.223518 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 00:54:27.223532 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 00:54:27.223547 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 00:54:27.223561 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 00:54:27.223575 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 00:54:27.223674 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 00:54:27.223710 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 00:54:27.223724 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 00:54:27.223739 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 00:54:27.223753 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 00:54:27.223767 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 00:54:27.223781 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 00:54:27.223795 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 00:54:27.223809 kernel: fuse: init (API version 7.39) Sep 4 00:54:27.223825 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 00:54:27.223839 kernel: loop: module loaded Sep 4 00:54:27.223853 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 00:54:27.223867 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 00:54:27.223881 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 00:54:27.224012 systemd-journald[1131]: Collecting audit messages is disabled. Sep 4 00:54:27.224044 systemd-journald[1131]: Journal started Sep 4 00:54:27.224074 systemd-journald[1131]: Runtime Journal (/run/log/journal/f6f327158d7c429f9027319c6d75ccb7) is 6.0M, max 48.3M, 42.3M free. Sep 4 00:54:26.934276 systemd[1]: Queued start job for default target multi-user.target. Sep 4 00:54:26.951342 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 4 00:54:26.951905 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 00:54:27.227828 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 00:54:27.230679 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 00:54:27.230716 systemd[1]: Stopped verity-setup.service. Sep 4 00:54:27.234625 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:27.238627 kernel: ACPI: bus type drm_connector registered Sep 4 00:54:27.238668 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 00:54:27.240369 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 00:54:27.242014 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 00:54:27.243627 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 00:54:27.245134 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 00:54:27.246843 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 00:54:27.248522 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 00:54:27.250056 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 00:54:27.252105 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 00:54:27.254145 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 00:54:27.254356 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 00:54:27.256349 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 00:54:27.256564 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 00:54:27.258783 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 00:54:27.258906 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 00:54:27.260758 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 00:54:27.260994 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 00:54:27.263079 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 00:54:27.263341 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 00:54:27.265034 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 00:54:27.265168 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 00:54:27.267168 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 00:54:27.269250 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 00:54:27.272050 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 00:54:27.283869 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 00:54:27.293747 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 00:54:27.297475 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 00:54:27.299002 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 00:54:27.299044 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 00:54:27.302542 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 4 00:54:27.305777 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 00:54:27.308751 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 00:54:27.310537 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 00:54:27.312344 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 00:54:27.314808 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 00:54:27.316473 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 00:54:27.318117 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 00:54:27.320710 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 00:54:27.328135 systemd-journald[1131]: Time spent on flushing to /var/log/journal/f6f327158d7c429f9027319c6d75ccb7 is 11.500ms for 932 entries. Sep 4 00:54:27.328135 systemd-journald[1131]: System Journal (/var/log/journal/f6f327158d7c429f9027319c6d75ccb7) is 8.0M, max 195.6M, 187.6M free. Sep 4 00:54:27.346980 systemd-journald[1131]: Received client request to flush runtime journal. Sep 4 00:54:27.324756 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 00:54:27.329997 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 00:54:27.336789 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 00:54:27.340133 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 00:54:27.342403 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 00:54:27.344182 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 00:54:27.346262 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 00:54:27.348548 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 00:54:27.352639 kernel: loop0: detected capacity change from 0 to 142488 Sep 4 00:54:27.354772 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 00:54:27.360468 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 00:54:27.363553 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 00:54:27.372957 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 4 00:54:27.381979 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 00:54:27.386736 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 00:54:27.397188 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 00:54:27.399417 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 00:54:27.402225 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 4 00:54:27.408780 udevadm[1189]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 4 00:54:27.417021 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 00:54:27.421670 kernel: loop1: detected capacity change from 0 to 140768 Sep 4 00:54:27.442001 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. Sep 4 00:54:27.442429 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. Sep 4 00:54:27.448910 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 00:54:27.458697 kernel: loop2: detected capacity change from 0 to 219840 Sep 4 00:54:27.494654 kernel: loop3: detected capacity change from 0 to 142488 Sep 4 00:54:27.512645 kernel: loop4: detected capacity change from 0 to 140768 Sep 4 00:54:27.524641 kernel: loop5: detected capacity change from 0 to 219840 Sep 4 00:54:27.534824 (sd-merge)[1199]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 4 00:54:27.536449 (sd-merge)[1199]: Merged extensions into '/usr'. Sep 4 00:54:27.543772 systemd[1]: Reloading requested from client PID 1171 ('systemd-sysext') (unit systemd-sysext.service)... Sep 4 00:54:27.543801 systemd[1]: Reloading... Sep 4 00:54:27.603013 zram_generator::config[1221]: No configuration found. Sep 4 00:54:27.671040 ldconfig[1166]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 00:54:27.713858 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. Sep 4 00:54:27.751238 systemd[1]: Reloading finished in 206 ms. Sep 4 00:54:27.784974 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 00:54:27.787128 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 00:54:27.807050 systemd[1]: Starting ensure-sysext.service... Sep 4 00:54:27.809518 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 00:54:27.814489 systemd[1]: Reloading requested from client PID 1262 ('systemctl') (unit ensure-sysext.service)... Sep 4 00:54:27.814498 systemd[1]: Reloading... Sep 4 00:54:27.828228 systemd-tmpfiles[1263]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 00:54:27.828447 systemd-tmpfiles[1263]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 00:54:27.829308 systemd-tmpfiles[1263]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 00:54:27.829487 systemd-tmpfiles[1263]: ACLs are not supported, ignoring. Sep 4 00:54:27.829536 systemd-tmpfiles[1263]: ACLs are not supported, ignoring. Sep 4 00:54:27.833646 systemd-tmpfiles[1263]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 00:54:27.833659 systemd-tmpfiles[1263]: Skipping /boot Sep 4 00:54:27.845362 systemd-tmpfiles[1263]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 00:54:27.845373 systemd-tmpfiles[1263]: Skipping /boot Sep 4 00:54:27.862649 zram_generator::config[1292]: No configuration found. Sep 4 00:54:27.968560 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. Sep 4 00:54:28.047201 kernel: hrtimer: interrupt took 7797659 ns Sep 4 00:54:28.057786 systemd[1]: Reloading finished in 243 ms. Sep 4 00:54:28.076278 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 00:54:28.091226 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 00:54:28.103791 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 00:54:28.108161 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 00:54:28.114535 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 00:54:28.119084 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 00:54:28.123009 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 00:54:28.125702 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 00:54:28.129481 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:28.129631 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 00:54:28.130970 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 00:54:28.138820 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 00:54:28.141970 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 00:54:28.143617 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 00:54:28.145906 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 00:54:28.145997 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:28.146720 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 00:54:28.147239 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 00:54:28.151108 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 00:54:28.151223 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 00:54:28.154220 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 00:54:28.154502 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 00:54:28.162068 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 00:54:28.166259 augenrules[1355]: No rules Sep 4 00:54:28.168084 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 00:54:28.175813 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 00:54:28.180767 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:28.180908 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 00:54:28.189905 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 00:54:28.191444 systemd-udevd[1339]: Using default interface naming scheme 'v255'. Sep 4 00:54:28.193339 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 00:54:28.200192 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 00:54:28.202483 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 00:54:28.204518 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 00:54:28.206895 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:28.208341 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 00:54:28.212017 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 00:54:28.212208 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 00:54:28.215892 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 00:54:28.216108 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 00:54:28.219503 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 00:54:28.219769 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 00:54:28.225120 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 00:54:28.231621 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 00:54:28.235145 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 00:54:28.242296 systemd[1]: Finished ensure-sysext.service. Sep 4 00:54:28.253259 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:28.253428 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 00:54:28.261888 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 00:54:28.268016 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 00:54:28.281882 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 00:54:28.284252 systemd-resolved[1338]: Positive Trust Anchors: Sep 4 00:54:28.284260 systemd-resolved[1338]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 00:54:28.284285 systemd-resolved[1338]: 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 Sep 4 00:54:28.289538 systemd-resolved[1338]: Defaulting to hostname 'linux'. Sep 4 00:54:28.291723 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 00:54:28.295279 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1375) Sep 4 00:54:28.299047 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 00:54:28.301281 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 00:54:28.307144 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 4 00:54:28.308737 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). Sep 4 00:54:28.308772 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 4 00:54:28.309069 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 00:54:28.311087 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 00:54:28.311192 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 00:54:28.313875 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 00:54:28.314007 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 00:54:28.318903 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 00:54:28.319035 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 00:54:28.325417 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 00:54:28.325554 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 00:54:28.328271 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 4 00:54:28.336661 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 00:54:28.338609 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 00:54:28.348052 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 00:54:28.350123 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 00:54:28.350258 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 00:54:28.355706 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Sep 4 00:54:28.360496 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 00:54:28.366818 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 4 00:54:28.371185 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Sep 4 00:54:28.371360 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 4 00:54:28.374697 kernel: ACPI: button: Power Button [PWRF] Sep 4 00:54:28.374741 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 4 00:54:28.422883 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 4 00:54:28.426161 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 00:54:28.439430 systemd-networkd[1405]: lo: Link UP Sep 4 00:54:28.439438 systemd-networkd[1405]: lo: Gained carrier Sep 4 00:54:28.444336 systemd-networkd[1405]: Enumeration completed Sep 4 00:54:28.446988 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 00:54:28.448783 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 00:54:28.450724 systemd-networkd[1405]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:54:28.450738 systemd-networkd[1405]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 00:54:28.451420 systemd[1]: Reached target network.target - Network. Sep 4 00:54:28.455816 systemd-networkd[1405]: eth0: Link UP Sep 4 00:54:28.455831 systemd-networkd[1405]: eth0: Gained carrier Sep 4 00:54:28.455850 systemd-networkd[1405]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 00:54:28.458948 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 00:54:28.472688 systemd-networkd[1405]: eth0: DHCPv4 address 10.0.0.50/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 00:54:28.473365 systemd-timesyncd[1407]: Network configuration changed, trying to establish connection. Sep 4 00:54:28.955123 systemd-timesyncd[1407]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 4 00:54:28.955167 systemd-timesyncd[1407]: Initial clock synchronization to Fri 2026-09-04 00:54:28.954954 UTC. Sep 4 00:54:28.956525 systemd-resolved[1338]: Clock change detected. Flushing caches. Sep 4 00:54:29.005805 kernel: mousedev: PS/2 mouse device common for all mice Sep 4 00:54:29.080583 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 00:54:29.114252 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 00:54:29.116452 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 00:54:29.123121 lvm[1431]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 00:54:29.159163 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 00:54:29.161434 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 00:54:29.163263 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 00:54:29.165286 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 00:54:29.168169 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 00:54:29.170570 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 00:54:29.172464 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 00:54:29.174477 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 00:54:29.176316 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 00:54:29.176354 systemd[1]: Reached target paths.target - Path Units. Sep 4 00:54:29.177747 systemd[1]: Reached target timers.target - Timer Units. Sep 4 00:54:29.180700 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 00:54:29.185376 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 00:54:29.200578 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 00:54:29.204707 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 00:54:29.207135 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 00:54:29.208714 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 00:54:29.210249 systemd[1]: Reached target basic.target - Basic System. Sep 4 00:54:29.212174 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 00:54:29.212209 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 00:54:29.223141 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 00:54:29.229349 lvm[1436]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 00:54:29.227565 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 00:54:29.230310 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 00:54:29.234416 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 00:54:29.237681 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). Sep 4 00:54:29.241402 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 00:54:29.243923 jq[1439]: false Sep 4 00:54:29.244067 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 00:54:29.248739 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 00:54:29.254555 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 00:54:29.256373 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 00:54:29.256735 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 00:54:29.258142 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 00:54:29.262599 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 00:54:29.263535 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 00:54:29.274447 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 00:54:29.274612 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 00:54:29.274960 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 00:54:29.275122 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 00:54:29.280244 extend-filesystems[1440]: Found loop3 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found loop4 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found loop5 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found sr0 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda1 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda2 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda3 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found usr Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda4 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda6 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda7 Sep 4 00:54:29.280244 extend-filesystems[1440]: Found vda9 Sep 4 00:54:29.280244 extend-filesystems[1440]: Checking size of /dev/vda9 Sep 4 00:54:29.353278 jq[1448]: true Sep 4 00:54:29.346377 dbus-daemon[1438]: [system] SELinux support is enabled Sep 4 00:54:29.353701 update_engine[1447]: I20260904 00:54:29.341696 1447 main.cc:92] Flatcar Update Engine starting Sep 4 00:54:29.353701 update_engine[1447]: I20260904 00:54:29.352707 1447 update_check_scheduler.cc:74] Next update check in 10m28s Sep 4 00:54:29.340946 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 00:54:29.341280 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 00:54:29.354411 jq[1460]: true Sep 4 00:54:29.357830 extend-filesystems[1440]: Resized partition /dev/vda9 Sep 4 00:54:29.367290 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1375) Sep 4 00:54:29.359944 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 00:54:29.372558 extend-filesystems[1468]: resize2fs 1.47.1 (20-May-2024) Sep 4 00:54:29.373667 (ntainerd)[1464]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 00:54:29.384033 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 4 00:54:29.395682 systemd[1]: Started update-engine.service - Update Engine. Sep 4 00:54:29.399470 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). Sep 4 00:54:29.399867 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 00:54:29.403509 systemd-logind[1445]: Watching system buttons on /dev/input/event1 (Power Button) Sep 4 00:54:29.403921 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). Sep 4 00:54:29.403940 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 00:54:29.408736 systemd-logind[1445]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 4 00:54:29.415532 systemd-logind[1445]: New seat seat0. Sep 4 00:54:29.416292 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 00:54:29.421368 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 00:54:29.434228 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 4 00:54:29.453038 extend-filesystems[1468]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 4 00:54:29.453038 extend-filesystems[1468]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 4 00:54:29.453038 extend-filesystems[1468]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 4 00:54:29.459050 extend-filesystems[1440]: Resized filesystem in /dev/vda9 Sep 4 00:54:29.457073 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 00:54:29.457301 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 00:54:29.463941 locksmithd[1478]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 00:54:29.470505 bash[1489]: Updated "/home/core/.ssh/authorized_keys" Sep 4 00:54:29.472379 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 00:54:29.478061 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 00:54:29.536301 sshd_keygen[1466]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 00:54:29.565491 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 00:54:29.576391 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 00:54:29.586990 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 00:54:29.587175 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 00:54:29.588517 containerd[1464]: time="2026-09-04T00:54:29.588325643Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Sep 4 00:54:29.602657 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 00:54:29.618590 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 00:54:29.624462 containerd[1464]: time="2026-09-04T00:54:29.624403897Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 00:54:29.626679 containerd[1464]: time="2026-09-04T00:54:29.626597494Z" 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.156-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 4 00:54:29.626679 containerd[1464]: time="2026-09-04T00:54:29.626656872Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 00:54:29.626679 containerd[1464]: time="2026-09-04T00:54:29.626683423Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 00:54:29.626952 containerd[1464]: time="2026-09-04T00:54:29.626920507Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 00:54:29.627192 containerd[1464]: time="2026-09-04T00:54:29.626944698Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 00:54:29.627422 containerd[1464]: time="2026-09-04T00:54:29.627379126Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 00:54:29.627455 containerd[1464]: time="2026-09-04T00:54:29.627428553Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 00:54:29.627866 containerd[1464]: time="2026-09-04T00:54:29.627734485Z" 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 Sep 4 00:54:29.627866 containerd[1464]: time="2026-09-04T00:54:29.627812837Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 00:54:29.627866 containerd[1464]: time="2026-09-04T00:54:29.627830236Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 00:54:29.627866 containerd[1464]: time="2026-09-04T00:54:29.627842877Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 00:54:29.627983 containerd[1464]: time="2026-09-04T00:54:29.627928045Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 00:54:29.628333 containerd[1464]: time="2026-09-04T00:54:29.628279066Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 00:54:29.628532 containerd[1464]: time="2026-09-04T00:54:29.628503509Z" 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 Sep 4 00:54:29.628565 containerd[1464]: time="2026-09-04T00:54:29.628533990Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 00:54:29.628663 containerd[1464]: time="2026-09-04T00:54:29.628640893Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 00:54:29.628718 containerd[1464]: time="2026-09-04T00:54:29.628701158Z" level=info msg="metadata content store policy set" policy=shared Sep 4 00:54:29.633675 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 00:54:29.633997 containerd[1464]: time="2026-09-04T00:54:29.633725102Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 00:54:29.635929 containerd[1464]: time="2026-09-04T00:54:29.635747334Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 00:54:29.635929 containerd[1464]: time="2026-09-04T00:54:29.635850125Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 00:54:29.635929 containerd[1464]: time="2026-09-04T00:54:29.635875037Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 00:54:29.635929 containerd[1464]: time="2026-09-04T00:54:29.635896782Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 00:54:29.636270 containerd[1464]: time="2026-09-04T00:54:29.636251690Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.636956877Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637129815Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637161288Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637309303Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637427071Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637461467Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637485161Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637509917Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637533437Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637557783Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637578437Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637602123Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637688600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.637975 containerd[1464]: time="2026-09-04T00:54:29.637712211Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.637733715Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638036531Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638070934Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638116272Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638137583Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638154326Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638175419Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638209188Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638235147Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638260474Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638282071Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638314277Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638353152Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638375149Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638463 containerd[1464]: time="2026-09-04T00:54:29.638394049Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 00:54:29.638124 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638457187Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638488794Z" 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 Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638510078Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638531757Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638546278Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638566202Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638583231Z" level=info msg="NRI interface is disabled by configuration." Sep 4 00:54:29.638904 containerd[1464]: time="2026-09-04T00:54:29.638597253Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 00:54:29.639305 containerd[1464]: time="2026-09-04T00:54:29.639194855Z" 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}" Sep 4 00:54:29.639462 containerd[1464]: time="2026-09-04T00:54:29.639309485Z" level=info msg="Connect containerd service" Sep 4 00:54:29.639462 containerd[1464]: time="2026-09-04T00:54:29.639357389Z" level=info msg="using legacy CRI server" Sep 4 00:54:29.639462 containerd[1464]: time="2026-09-04T00:54:29.639366826Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 00:54:29.639529 containerd[1464]: time="2026-09-04T00:54:29.639475424Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 00:54:29.640246 containerd[1464]: time="2026-09-04T00:54:29.640143844Z" 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" Sep 4 00:54:29.641317 containerd[1464]: time="2026-09-04T00:54:29.641087608Z" level=info msg="Start subscribing containerd event" Sep 4 00:54:29.641317 containerd[1464]: time="2026-09-04T00:54:29.641197183Z" level=info msg="Start recovering state" Sep 4 00:54:29.641309 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 00:54:29.641509 containerd[1464]: time="2026-09-04T00:54:29.641404986Z" level=info msg="Start event monitor" Sep 4 00:54:29.641538 containerd[1464]: time="2026-09-04T00:54:29.641514345Z" level=info msg="Start snapshots syncer" Sep 4 00:54:29.641538 containerd[1464]: time="2026-09-04T00:54:29.641527841Z" level=info msg="Start cni network conf syncer for default" Sep 4 00:54:29.641583 containerd[1464]: time="2026-09-04T00:54:29.641544744Z" level=info msg="Start streaming server" Sep 4 00:54:29.642037 containerd[1464]: time="2026-09-04T00:54:29.641971535Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 00:54:29.642390 containerd[1464]: time="2026-09-04T00:54:29.642237301Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 00:54:29.645841 containerd[1464]: time="2026-09-04T00:54:29.644906181Z" level=info msg="containerd successfully booted in 0.057463s" Sep 4 00:54:29.645438 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 00:54:30.993712 systemd-networkd[1405]: eth0: Gained IPv6LL Sep 4 00:54:31.006565 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 00:54:31.013525 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 00:54:31.054389 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 00:54:31.057821 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 00:54:31.061277 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 00:54:31.079385 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 00:54:31.079594 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 00:54:31.082373 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 00:54:31.083468 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 00:54:32.293025 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 00:54:32.295741 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 00:54:32.297725 systemd[1]: Startup finished in 1.003s (kernel) + 4.806s (initrd) + 5.348s (userspace) = 11.158s. Sep 4 00:54:32.338089 (kubelet)[1544]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 4 00:54:32.713193 kubelet[1544]: E0904 00:54:32.712940 1544 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" Sep 4 00:54:32.715498 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 4 00:54:32.715616 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 4 00:54:32.715864 systemd[1]: kubelet.service: Consumed 1.381s CPU time. Sep 4 00:54:35.591660 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 00:54:35.592971 systemd[1]: Started sshd@0-10.0.0.50:22-10.0.0.1:33036.service - OpenSSH per-connection server daemon (10.0.0.1:33036). Sep 4 00:54:35.632945 sshd[1558]: Accepted publickey for core from 10.0.0.1 port 33036 ssh2: RSA SHA256:W9+FcEojPE3+/wl2tUZpc+RtoQaVQPEhadP/BSvJhzA Sep 4 00:54:35.635495 sshd[1558]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:54:35.645201 systemd-logind[1445]: New session 1 of user core. Sep 4 00:54:35.646079 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 00:54:35.658198 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 00:54:35.668994 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 00:54:35.672175 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 00:54:35.679734 (systemd)[1562]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 00:54:35.754246 systemd[1562]: Queued start job for default target default.target. Sep 4 00:54:35.769087 systemd[1562]: Created slice app.slice - User Application Slice. Sep 4 00:54:35.769145 systemd[1562]: Reached target paths.target - Paths. Sep 4 00:54:35.769155 systemd[1562]: Reached target timers.target - Timers. Sep 4 00:54:35.770481 systemd[1562]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 00:54:35.783545 systemd[1562]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 00:54:35.783650 systemd[1562]: Reached target sockets.target - Sockets. Sep 4 00:54:35.783660 systemd[1562]: Reached target basic.target - Basic System. Sep 4 00:54:35.783688 systemd[1562]: Reached target default.target - Main User Target. Sep 4 00:54:35.783709 systemd[1562]: Startup finished in 97ms. Sep 4 00:54:35.783989 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 00:54:35.793255 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 00:54:35.855679 systemd[1]: Started sshd@1-10.0.0.50:22-10.0.0.1:33042.service - OpenSSH per-connection server daemon (10.0.0.1:33042). Sep 4 00:54:35.887251 sshd[1573]: Accepted publickey for core from 10.0.0.1 port 33042 ssh2: RSA SHA256:W9+FcEojPE3+/wl2tUZpc+RtoQaVQPEhadP/BSvJhzA Sep 4 00:54:35.888452 sshd[1573]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:54:35.892467 systemd-logind[1445]: New session 2 of user core. Sep 4 00:54:35.907171 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 00:54:35.960600 sshd[1573]: pam_unix(sshd:session): session closed for user core Sep 4 00:54:35.971045 systemd[1]: sshd@1-10.0.0.50:22-10.0.0.1:33042.service: Deactivated successfully. Sep 4 00:54:35.972378 systemd[1]: session-2.scope: Deactivated successfully. Sep 4 00:54:35.973373 systemd-logind[1445]: Session 2 logged out. Waiting for processes to exit. Sep 4 00:54:35.974287 systemd[1]: Started sshd@2-10.0.0.50:22-10.0.0.1:33044.service - OpenSSH per-connection server daemon (10.0.0.1:33044). Sep 4 00:54:35.974864 systemd-logind[1445]: Removed session 2. Sep 4 00:54:36.002396 sshd[1580]: Accepted publickey for core from 10.0.0.1 port 33044 ssh2: RSA SHA256:W9+FcEojPE3+/wl2tUZpc+RtoQaVQPEhadP/BSvJhzA Sep 4 00:54:36.003401 sshd[1580]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:54:36.006499 systemd-logind[1445]: New session 3 of user core. Sep 4 00:54:36.017164 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 00:54:36.067345 sshd[1580]: pam_unix(sshd:session): session closed for user core Sep 4 00:54:36.074571 systemd[1]: sshd@2-10.0.0.50:22-10.0.0.1:33044.service: Deactivated successfully. Sep 4 00:54:36.075789 systemd[1]: session-3.scope: Deactivated successfully. Sep 4 00:54:36.076670 systemd-logind[1445]: Session 3 logged out. Waiting for processes to exit. Sep 4 00:54:36.081017 systemd[1]: Started sshd@3-10.0.0.50:22-10.0.0.1:33054.service - OpenSSH per-connection server daemon (10.0.0.1:33054). Sep 4 00:54:36.082011 systemd-logind[1445]: Removed session 3. Sep 4 00:54:36.106691 sshd[1587]: Accepted publickey for core from 10.0.0.1 port 33054 ssh2: RSA SHA256:W9+FcEojPE3+/wl2tUZpc+RtoQaVQPEhadP/BSvJhzA Sep 4 00:54:36.108339 sshd[1587]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:54:36.111681 systemd-logind[1445]: New session 4 of user core. Sep 4 00:54:36.127243 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 00:54:36.181400 sshd[1587]: pam_unix(sshd:session): session closed for user core Sep 4 00:54:36.200415 systemd[1]: sshd@3-10.0.0.50:22-10.0.0.1:33054.service: Deactivated successfully. Sep 4 00:54:36.202071 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 00:54:36.203412 systemd-logind[1445]: Session 4 logged out. Waiting for processes to exit. Sep 4 00:54:36.210054 systemd[1]: Started sshd@4-10.0.0.50:22-10.0.0.1:33058.service - OpenSSH per-connection server daemon (10.0.0.1:33058). Sep 4 00:54:36.210657 systemd-logind[1445]: Removed session 4. Sep 4 00:54:36.234177 sshd[1594]: Accepted publickey for core from 10.0.0.1 port 33058 ssh2: RSA SHA256:W9+FcEojPE3+/wl2tUZpc+RtoQaVQPEhadP/BSvJhzA Sep 4 00:54:36.235171 sshd[1594]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 00:54:36.238392 systemd-logind[1445]: New session 5 of user core. Sep 4 00:54:36.251916 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 00:54:36.313817 sudo[1597]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 4 00:54:36.314307 sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 00:54:36.333234 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 00:54:36.355916 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 00:54:36.356175 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 00:54:36.908925 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 00:54:36.909091 systemd[1]: kubelet.service: Consumed 1.381s CPU time. Sep 4 00:54:36.919024 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 00:54:36.943158 systemd[1]: Reloading requested from client PID 1645 ('systemctl') (unit session-5.scope)... Sep 4 00:54:36.943177 systemd[1]: Reloading... Sep 4 00:54:37.003839 zram_generator::config[1683]: No configuration found. Sep 4 00:54:37.112566 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. Sep 4 00:54:37.162802 systemd[1]: Reloading finished in 219 ms. Sep 4 00:54:37.202592 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 4 00:54:37.202650 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 4 00:54:37.202934 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 00:54:37.204733 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 00:54:37.311400 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 00:54:37.316038 (kubelet)[1732]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 4 00:54:37.357038 kubelet[1732]: 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. Sep 4 00:54:37.506345 kubelet[1732]: I0904 00:54:37.506000 1732 server.go:545] "Kubelet version" kubeletVersion="v1.36.1" Sep 4 00:54:37.506345 kubelet[1732]: I0904 00:54:37.506173 1732 server.go:547] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 4 00:54:37.506345 kubelet[1732]: I0904 00:54:37.506198 1732 watchdog_linux.go:94] "Systemd watchdog is not enabled" Sep 4 00:54:37.506345 kubelet[1732]: I0904 00:54:37.506203 1732 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 4 00:54:37.506966 kubelet[1732]: I0904 00:54:37.506943 1732 server.go:985] "Client rotation is on, will bootstrap in background" Sep 4 00:54:37.512652 kubelet[1732]: I0904 00:54:37.512474 1732 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 4 00:54:37.518195 kubelet[1732]: E0904 00:54:37.518128 1732 remote_runtime.go:1177] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Sep 4 00:54:37.518195 kubelet[1732]: I0904 00:54:37.518192 1732 server.go:1425] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Sep 4 00:54:37.524273 kubelet[1732]: I0904 00:54:37.524199 1732 server.go:808] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Sep 4 00:54:37.524623 kubelet[1732]: I0904 00:54:37.524546 1732 container_manager_linux.go:273] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 4 00:54:37.524796 kubelet[1732]: I0904 00:54:37.524616 1732 container_manager_linux.go:278] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.50","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"MemoryReservationPolicy":"None","PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 4 00:54:37.524796 kubelet[1732]: I0904 00:54:37.524785 1732 topology_manager.go:172] "Creating topology manager with none policy" Sep 4 00:54:37.524796 kubelet[1732]: I0904 00:54:37.524793 1732 container_manager_linux.go:309] "Creating device plugin manager" Sep 4 00:54:37.525007 kubelet[1732]: I0904 00:54:37.524890 1732 container_manager_linux.go:318] "Creating Dynamic Resource Allocation (DRA) manager" Sep 4 00:54:37.599287 kubelet[1732]: I0904 00:54:37.599145 1732 state_mem.go:45] "Initialized" logger="CPUManager state memory" Sep 4 00:54:37.600749 kubelet[1732]: I0904 00:54:37.600133 1732 kubelet.go:485] "Attempting to sync node with API server" Sep 4 00:54:37.600749 kubelet[1732]: I0904 00:54:37.600175 1732 kubelet.go:386] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 4 00:54:37.600749 kubelet[1732]: I0904 00:54:37.600206 1732 kubelet.go:397] "Adding apiserver pod source" Sep 4 00:54:37.600749 kubelet[1732]: I0904 00:54:37.600221 1732 apiserver.go:41] "Waiting for node sync before watching apiserver pods" Sep 4 00:54:37.600749 kubelet[1732]: E0904 00:54:37.600364 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:37.600749 kubelet[1732]: E0904 00:54:37.600410 1732 file.go:97] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:37.602649 kubelet[1732]: I0904 00:54:37.602632 1732 kuberuntime_manager.go:308] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Sep 4 00:54:37.604684 kubelet[1732]: I0904 00:54:37.604643 1732 kubelet.go:1002] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 4 00:54:37.604741 kubelet[1732]: I0904 00:54:37.604694 1732 kubelet.go:1029] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Sep 4 00:54:37.604808 kubelet[1732]: W0904 00:54:37.604789 1732 probe.go:275] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 4 00:54:37.609485 kubelet[1732]: I0904 00:54:37.609456 1732 server.go:1285] "Started kubelet" Sep 4 00:54:37.610822 kubelet[1732]: I0904 00:54:37.610801 1732 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer" Sep 4 00:54:37.611211 kubelet[1732]: I0904 00:54:37.611170 1732 server.go:193] "Starting to listen" address="0.0.0.0" port=10250 Sep 4 00:54:37.612124 kubelet[1732]: I0904 00:54:37.612067 1732 server.go:354] "Adding debug handlers to kubelet server" Sep 4 00:54:37.614478 kubelet[1732]: I0904 00:54:37.614404 1732 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 Sep 4 00:54:37.615422 kubelet[1732]: I0904 00:54:37.614934 1732 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 4 00:54:37.615422 kubelet[1732]: I0904 00:54:37.614981 1732 server_v1.go:49] "podresources" method="list" useActivePods=true Sep 4 00:54:37.615422 kubelet[1732]: I0904 00:54:37.615225 1732 server.go:264] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 4 00:54:37.615876 kubelet[1732]: I0904 00:54:37.615840 1732 factory.go:222] Registration of the containerd container factory successfully Sep 4 00:54:37.615876 kubelet[1732]: I0904 00:54:37.615854 1732 factory.go:222] Registration of the systemd container factory successfully Sep 4 00:54:37.616791 kubelet[1732]: I0904 00:54:37.616488 1732 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 4 00:54:37.617182 kubelet[1732]: I0904 00:54:37.617131 1732 tlsconfig.go:243] "Starting DynamicServingCertificateController" Sep 4 00:54:37.618473 kubelet[1732]: I0904 00:54:37.618367 1732 volume_manager.go:310] "Starting Kubelet Volume Manager" Sep 4 00:54:37.619163 kubelet[1732]: E0904 00:54:37.619136 1732 kubelet_node_status.go:396] "Error getting the current node from lister" err="node \"10.0.0.50\" not found" Sep 4 00:54:37.619446 kubelet[1732]: E0904 00:54:37.619390 1732 kubelet.go:3516] "Unable to register mirror pod because node is not registered yet" err="node \"10.0.0.50\" not found" node="10.0.0.50" Sep 4 00:54:37.619705 kubelet[1732]: I0904 00:54:37.619534 1732 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Sep 4 00:54:37.619923 kubelet[1732]: I0904 00:54:37.619873 1732 reconciler.go:29] "Reconciler: start to sync state" Sep 4 00:54:37.623208 kubelet[1732]: E0904 00:54:37.621901 1732 kubelet.go:1725] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 4 00:54:37.625292 kubelet[1732]: E0904 00:54:37.624544 1732 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.50\" not found" node="10.0.0.50" Sep 4 00:54:37.628610 kubelet[1732]: I0904 00:54:37.628575 1732 cpu_manager.go:235] "Starting" policy="none" Sep 4 00:54:37.628610 kubelet[1732]: I0904 00:54:37.628598 1732 cpu_manager.go:236] "Reconciling" reconcilePeriod="10s" Sep 4 00:54:37.628710 kubelet[1732]: I0904 00:54:37.628616 1732 state_mem.go:45] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory" Sep 4 00:54:37.631319 kubelet[1732]: I0904 00:54:37.631254 1732 policy_none.go:50] "Start" Sep 4 00:54:37.631402 kubelet[1732]: I0904 00:54:37.631371 1732 memory_manager.go:190] "Starting memorymanager" policy="None" Sep 4 00:54:37.631503 kubelet[1732]: I0904 00:54:37.631486 1732 state_mem.go:40] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Sep 4 00:54:37.633843 kubelet[1732]: I0904 00:54:37.633787 1732 policy_none.go:44] "Start" Sep 4 00:54:37.639961 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 4 00:54:37.654569 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 4 00:54:37.664469 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 4 00:54:37.666856 kubelet[1732]: E0904 00:54:37.666627 1732 manager.go:525] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 4 00:54:37.667023 kubelet[1732]: I0904 00:54:37.666968 1732 eviction_manager.go:194] "Eviction manager: starting control loop" Sep 4 00:54:37.667023 kubelet[1732]: I0904 00:54:37.666986 1732 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 4 00:54:37.667276 kubelet[1732]: I0904 00:54:37.667262 1732 plugin_manager.go:121] "Starting Kubelet Plugin Manager" Sep 4 00:54:37.668181 kubelet[1732]: E0904 00:54:37.668059 1732 eviction_manager.go:272] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 4 00:54:37.668181 kubelet[1732]: E0904 00:54:37.668125 1732 eviction_manager.go:297] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.50\" not found" Sep 4 00:54:37.687734 kubelet[1732]: I0904 00:54:37.687686 1732 kubelet_network_linux.go:53] "Initialized iptables rules." protocol="IPv4" Sep 4 00:54:37.688708 kubelet[1732]: I0904 00:54:37.688683 1732 kubelet_network_linux.go:53] "Initialized iptables rules." protocol="IPv6" Sep 4 00:54:37.688743 kubelet[1732]: I0904 00:54:37.688736 1732 status_manager.go:277] "Starting to sync pod status with apiserver" Sep 4 00:54:37.688873 kubelet[1732]: I0904 00:54:37.688785 1732 kubelet.go:2622] "Starting kubelet main sync loop" Sep 4 00:54:37.688910 kubelet[1732]: E0904 00:54:37.688874 1732 kubelet.go:2646] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 4 00:54:37.720587 kubelet[1732]: E0904 00:54:37.720444 1732 kubelet.go:3516] "Unable to register mirror pod because node is not registered yet" err="node \"10.0.0.50\" not found" node="10.0.0.50" Sep 4 00:54:37.770424 kubelet[1732]: I0904 00:54:37.770273 1732 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.50" Sep 4 00:54:37.779178 kubelet[1732]: I0904 00:54:37.779060 1732 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.50" Sep 4 00:54:37.788462 kubelet[1732]: I0904 00:54:37.788414 1732 kuberuntime_manager.go:2146] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 4 00:54:37.788858 containerd[1464]: time="2026-09-04T00:54:37.788712100Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 4 00:54:37.789131 kubelet[1732]: I0904 00:54:37.788887 1732 kubelet_network.go:48] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 4 00:54:37.824397 sudo[1597]: pam_unix(sudo:session): session closed for user root Sep 4 00:54:37.826037 sshd[1594]: pam_unix(sshd:session): session closed for user core Sep 4 00:54:37.829009 systemd[1]: sshd@4-10.0.0.50:22-10.0.0.1:33058.service: Deactivated successfully. Sep 4 00:54:37.830378 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 00:54:37.830994 systemd-logind[1445]: Session 5 logged out. Waiting for processes to exit. Sep 4 00:54:37.831790 systemd-logind[1445]: Removed session 5. Sep 4 00:54:38.509745 kubelet[1732]: I0904 00:54:38.509614 1732 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 4 00:54:38.510540 kubelet[1732]: I0904 00:54:38.510240 1732 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" Sep 4 00:54:38.510540 kubelet[1732]: I0904 00:54:38.510290 1732 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" Sep 4 00:54:38.510540 kubelet[1732]: I0904 00:54:38.510347 1732 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" Sep 4 00:54:38.600858 kubelet[1732]: I0904 00:54:38.600812 1732 apiserver.go:51] "Watching apiserver" Sep 4 00:54:38.600858 kubelet[1732]: E0904 00:54:38.600836 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:38.618018 systemd[1]: Created slice kubepods-burstable-podebc6451d_4948_410e_bf42_14daaa59f817.slice - libcontainer container kubepods-burstable-podebc6451d_4948_410e_bf42_14daaa59f817.slice. Sep 4 00:54:38.620511 kubelet[1732]: I0904 00:54:38.620477 1732 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Sep 4 00:54:38.623626 kubelet[1732]: I0904 00:54:38.623519 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/ebc6451d-4948-410e-bf42-14daaa59f817-cni-plugin\") pod \"kube-flannel-ds-gkh4v\" (UID: \"ebc6451d-4948-410e-bf42-14daaa59f817\") " pod="kube-flannel/kube-flannel-ds-gkh4v" Sep 4 00:54:38.623983 kubelet[1732]: I0904 00:54:38.623883 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/ebc6451d-4948-410e-bf42-14daaa59f817-flannel-cfg\") pod \"kube-flannel-ds-gkh4v\" (UID: \"ebc6451d-4948-410e-bf42-14daaa59f817\") " pod="kube-flannel/kube-flannel-ds-gkh4v" Sep 4 00:54:38.624166 kubelet[1732]: I0904 00:54:38.624143 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac-kube-proxy\") pod \"kube-proxy-5ns28\" (UID: \"f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac\") " pod="kube-system/kube-proxy-5ns28" Sep 4 00:54:38.624204 kubelet[1732]: I0904 00:54:38.624175 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac-xtables-lock\") pod \"kube-proxy-5ns28\" (UID: \"f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac\") " pod="kube-system/kube-proxy-5ns28" Sep 4 00:54:38.624204 kubelet[1732]: I0904 00:54:38.624192 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4jp2s\" (UniqueName: \"kubernetes.io/projected/f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac-kube-api-access-4jp2s\") pod \"kube-proxy-5ns28\" (UID: \"f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac\") " pod="kube-system/kube-proxy-5ns28" Sep 4 00:54:38.624257 kubelet[1732]: I0904 00:54:38.624238 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/ebc6451d-4948-410e-bf42-14daaa59f817-run\") pod \"kube-flannel-ds-gkh4v\" (UID: \"ebc6451d-4948-410e-bf42-14daaa59f817\") " pod="kube-flannel/kube-flannel-ds-gkh4v" Sep 4 00:54:38.624294 kubelet[1732]: I0904 00:54:38.624275 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/ebc6451d-4948-410e-bf42-14daaa59f817-cni\") pod \"kube-flannel-ds-gkh4v\" (UID: \"ebc6451d-4948-410e-bf42-14daaa59f817\") " pod="kube-flannel/kube-flannel-ds-gkh4v" Sep 4 00:54:38.624374 kubelet[1732]: I0904 00:54:38.624348 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/ebc6451d-4948-410e-bf42-14daaa59f817-xtables-lock\") pod \"kube-flannel-ds-gkh4v\" (UID: \"ebc6451d-4948-410e-bf42-14daaa59f817\") " pod="kube-flannel/kube-flannel-ds-gkh4v" Sep 4 00:54:38.624399 kubelet[1732]: I0904 00:54:38.624381 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5kgbn\" (UniqueName: \"kubernetes.io/projected/ebc6451d-4948-410e-bf42-14daaa59f817-kube-api-access-5kgbn\") pod \"kube-flannel-ds-gkh4v\" (UID: \"ebc6451d-4948-410e-bf42-14daaa59f817\") " pod="kube-flannel/kube-flannel-ds-gkh4v" Sep 4 00:54:38.624399 kubelet[1732]: I0904 00:54:38.624394 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac-lib-modules\") pod \"kube-proxy-5ns28\" (UID: \"f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac\") " pod="kube-system/kube-proxy-5ns28" Sep 4 00:54:38.632930 systemd[1]: Created slice kubepods-besteffort-podf6c1d65d_2c0a_4ee3_9881_a32ecf2940ac.slice - libcontainer container kubepods-besteffort-podf6c1d65d_2c0a_4ee3_9881_a32ecf2940ac.slice. Sep 4 00:54:38.933410 kubelet[1732]: E0904 00:54:38.933010 1732 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" Sep 4 00:54:38.934451 containerd[1464]: time="2026-09-04T00:54:38.934125378Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-gkh4v,Uid:ebc6451d-4948-410e-bf42-14daaa59f817,Namespace:kube-flannel,Attempt:0,}" Sep 4 00:54:38.945603 kubelet[1732]: E0904 00:54:38.945557 1732 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" Sep 4 00:54:38.946429 containerd[1464]: time="2026-09-04T00:54:38.946362700Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5ns28,Uid:f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac,Namespace:kube-system,Attempt:0,}" Sep 4 00:54:39.380574 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount227989463.mount: Deactivated successfully. Sep 4 00:54:39.386363 containerd[1464]: time="2026-09-04T00:54:39.386299374Z" 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\"}" Sep 4 00:54:39.387045 containerd[1464]: time="2026-09-04T00:54:39.387020682Z" 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\"}" Sep 4 00:54:39.387811 containerd[1464]: time="2026-09-04T00:54:39.387703660Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 4 00:54:39.388370 containerd[1464]: time="2026-09-04T00:54:39.388337437Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312116" Sep 4 00:54:39.388999 containerd[1464]: time="2026-09-04T00:54:39.388962633Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 00:54:39.392064 containerd[1464]: time="2026-09-04T00:54:39.392012612Z" 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\"}" Sep 4 00:54:39.392744 containerd[1464]: time="2026-09-04T00:54:39.392717430Z" 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 458.227309ms" Sep 4 00:54:39.393415 containerd[1464]: time="2026-09-04T00:54:39.393390130Z" 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 446.942449ms" Sep 4 00:54:39.469975 containerd[1464]: time="2026-09-04T00:54:39.469874016Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 00:54:39.469975 containerd[1464]: time="2026-09-04T00:54:39.469966554Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 00:54:39.470172 containerd[1464]: time="2026-09-04T00:54:39.470003149Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:39.470281 containerd[1464]: time="2026-09-04T00:54:39.470132299Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:39.471420 containerd[1464]: time="2026-09-04T00:54:39.471343084Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 00:54:39.471483 containerd[1464]: time="2026-09-04T00:54:39.471404305Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 00:54:39.471483 containerd[1464]: time="2026-09-04T00:54:39.471437162Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:39.471595 containerd[1464]: time="2026-09-04T00:54:39.471509182Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:39.528268 systemd[1]: Started cri-containerd-1504c148000d2bd4ce5a63b774726678322cdadd722488da88f17c71bdbee691.scope - libcontainer container 1504c148000d2bd4ce5a63b774726678322cdadd722488da88f17c71bdbee691. Sep 4 00:54:39.529676 systemd[1]: Started cri-containerd-57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632.scope - libcontainer container 57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632. Sep 4 00:54:39.554183 containerd[1464]: time="2026-09-04T00:54:39.554042857Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5ns28,Uid:f6c1d65d-2c0a-4ee3-9881-a32ecf2940ac,Namespace:kube-system,Attempt:0,} returns sandbox id \"1504c148000d2bd4ce5a63b774726678322cdadd722488da88f17c71bdbee691\"" Sep 4 00:54:39.555732 kubelet[1732]: E0904 00:54:39.555452 1732 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" Sep 4 00:54:39.558414 containerd[1464]: time="2026-09-04T00:54:39.558374278Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.36.4\"" Sep 4 00:54:39.571125 containerd[1464]: time="2026-09-04T00:54:39.571050953Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-gkh4v,Uid:ebc6451d-4948-410e-bf42-14daaa59f817,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632\"" Sep 4 00:54:39.571911 kubelet[1732]: E0904 00:54:39.571883 1732 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" Sep 4 00:54:39.601407 kubelet[1732]: E0904 00:54:39.601358 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:40.160341 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3949715871.mount: Deactivated successfully. Sep 4 00:54:40.382523 containerd[1464]: time="2026-09-04T00:54:40.382323357Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.36.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:40.382981 containerd[1464]: time="2026-09-04T00:54:40.382919744Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.36.4: active requests=0, bytes read=30055997" Sep 4 00:54:40.383819 containerd[1464]: time="2026-09-04T00:54:40.383702790Z" level=info msg="ImageCreate event name:\"sha256:a5280d36e1aa3602794efd60dc157d55bbd1bed8f755b5c12e0a6ca5dce9d3bd\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:40.385721 containerd[1464]: time="2026-09-04T00:54:40.385675796Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:b33fcdd8319290736e566f5fc2225ca07d3c2dfc025fb006342791aa31be4603\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:40.386725 containerd[1464]: time="2026-09-04T00:54:40.386579081Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.36.4\" with image id \"sha256:a5280d36e1aa3602794efd60dc157d55bbd1bed8f755b5c12e0a6ca5dce9d3bd\", repo tag \"registry.k8s.io/kube-proxy:v1.36.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:b33fcdd8319290736e566f5fc2225ca07d3c2dfc025fb006342791aa31be4603\", size \"30054777\" in 827.921209ms" Sep 4 00:54:40.386725 containerd[1464]: time="2026-09-04T00:54:40.386609164Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.36.4\" returns image reference \"sha256:a5280d36e1aa3602794efd60dc157d55bbd1bed8f755b5c12e0a6ca5dce9d3bd\"" Sep 4 00:54:40.388979 containerd[1464]: time="2026-09-04T00:54:40.388922791Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 4 00:54:40.393400 containerd[1464]: time="2026-09-04T00:54:40.393339948Z" level=info msg="CreateContainer within sandbox \"1504c148000d2bd4ce5a63b774726678322cdadd722488da88f17c71bdbee691\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 4 00:54:40.405407 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1760536283.mount: Deactivated successfully. Sep 4 00:54:40.408566 containerd[1464]: time="2026-09-04T00:54:40.408529849Z" level=info msg="CreateContainer within sandbox \"1504c148000d2bd4ce5a63b774726678322cdadd722488da88f17c71bdbee691\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"e3f0013e6e7c19e26e47d97b2dd1dce852274fd6a1e96a632ed263bf1008e26d\"" Sep 4 00:54:40.409793 containerd[1464]: time="2026-09-04T00:54:40.409733233Z" level=info msg="StartContainer for \"e3f0013e6e7c19e26e47d97b2dd1dce852274fd6a1e96a632ed263bf1008e26d\"" Sep 4 00:54:40.438220 systemd[1]: Started cri-containerd-e3f0013e6e7c19e26e47d97b2dd1dce852274fd6a1e96a632ed263bf1008e26d.scope - libcontainer container e3f0013e6e7c19e26e47d97b2dd1dce852274fd6a1e96a632ed263bf1008e26d. Sep 4 00:54:40.461919 containerd[1464]: time="2026-09-04T00:54:40.461842609Z" level=info msg="StartContainer for \"e3f0013e6e7c19e26e47d97b2dd1dce852274fd6a1e96a632ed263bf1008e26d\" returns successfully" Sep 4 00:54:40.468852 kubelet[1732]: E0904 00:54:40.468568 1732 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" Sep 4 00:54:40.482946 kubelet[1732]: I0904 00:54:40.482732 1732 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="kube-system/kube-proxy-5ns28" podStartSLOduration=2.651784132 podStartE2EDuration="3.482715539s" totalImagesPullingTime="830.931407ms" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-09-04 00:54:37 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-04 00:54:40.482509186 +0000 UTC m=+3.162112311" watchObservedRunningTime="2026-09-04 00:54:40.482715539 +0000 UTC m=+3.162318676" Sep 4 00:54:40.601858 kubelet[1732]: E0904 00:54:40.601665 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:40.696037 kubelet[1732]: E0904 00:54:40.695940 1732 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" Sep 4 00:54:41.603169 kubelet[1732]: E0904 00:54:41.603015 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:41.716561 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount129881171.mount: Deactivated successfully. Sep 4 00:54:41.748621 containerd[1464]: time="2026-09-04T00:54:41.748548742Z" 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\"}" Sep 4 00:54:41.749086 containerd[1464]: time="2026-09-04T00:54:41.748925248Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Sep 4 00:54:41.749854 containerd[1464]: time="2026-09-04T00:54:41.749811134Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:41.753395 containerd[1464]: time="2026-09-04T00:54:41.753318829Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:41.754102 containerd[1464]: time="2026-09-04T00:54:41.754056965Z" 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.365099349s" Sep 4 00:54:41.754162 containerd[1464]: time="2026-09-04T00:54:41.754102177Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Sep 4 00:54:41.760269 containerd[1464]: time="2026-09-04T00:54:41.760221239Z" level=info msg="CreateContainer within sandbox \"57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 4 00:54:41.771827 containerd[1464]: time="2026-09-04T00:54:41.771796780Z" level=info msg="CreateContainer within sandbox \"57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac\"" Sep 4 00:54:41.772588 containerd[1464]: time="2026-09-04T00:54:41.772520214Z" level=info msg="StartContainer for \"3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac\"" Sep 4 00:54:41.793056 systemd[1]: run-containerd-runc-k8s.io-3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac-runc.xDGpSH.mount: Deactivated successfully. Sep 4 00:54:41.801918 systemd[1]: Started cri-containerd-3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac.scope - libcontainer container 3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac. Sep 4 00:54:41.820729 systemd[1]: cri-containerd-3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac.scope: Deactivated successfully. Sep 4 00:54:41.821171 containerd[1464]: time="2026-09-04T00:54:41.821094944Z" level=info msg="StartContainer for \"3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac\" returns successfully" Sep 4 00:54:41.824595 kubelet[1732]: E0904 00:54:41.824560 1732 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" Sep 4 00:54:41.928832 containerd[1464]: time="2026-09-04T00:54:41.928646729Z" level=info msg="shim disconnected" id=3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac namespace=k8s.io Sep 4 00:54:41.928832 containerd[1464]: time="2026-09-04T00:54:41.928697382Z" level=warning msg="cleaning up after shim disconnected" id=3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac namespace=k8s.io Sep 4 00:54:41.928832 containerd[1464]: time="2026-09-04T00:54:41.928705009Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 00:54:42.604485 kubelet[1732]: E0904 00:54:42.604298 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:42.702787 kubelet[1732]: E0904 00:54:42.702658 1732 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" Sep 4 00:54:42.705229 containerd[1464]: time="2026-09-04T00:54:42.705105103Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 4 00:54:42.768743 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3ee02d835869531ef6119861084cee838061e8adeb56e90896fcb41c800f1dac-rootfs.mount: Deactivated successfully. Sep 4 00:54:43.604996 kubelet[1732]: E0904 00:54:43.604808 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:44.472151 containerd[1464]: time="2026-09-04T00:54:44.472065927Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:44.472638 containerd[1464]: time="2026-09-04T00:54:44.472597246Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Sep 4 00:54:44.473950 containerd[1464]: time="2026-09-04T00:54:44.473905761Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:44.479201 containerd[1464]: time="2026-09-04T00:54:44.476860733Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:44.479201 containerd[1464]: time="2026-09-04T00:54:44.478961773Z" 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 1.773794495s" Sep 4 00:54:44.479201 containerd[1464]: time="2026-09-04T00:54:44.478983912Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Sep 4 00:54:44.485324 containerd[1464]: time="2026-09-04T00:54:44.485232617Z" level=info msg="CreateContainer within sandbox \"57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 4 00:54:44.494850 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1105546065.mount: Deactivated successfully. Sep 4 00:54:44.495989 containerd[1464]: time="2026-09-04T00:54:44.495944293Z" level=info msg="CreateContainer within sandbox \"57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776\"" Sep 4 00:54:44.497975 containerd[1464]: time="2026-09-04T00:54:44.496728193Z" level=info msg="StartContainer for \"914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776\"" Sep 4 00:54:44.531028 systemd[1]: Started cri-containerd-914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776.scope - libcontainer container 914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776. Sep 4 00:54:44.555139 systemd[1]: cri-containerd-914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776.scope: Deactivated successfully. Sep 4 00:54:44.556944 containerd[1464]: time="2026-09-04T00:54:44.556912363Z" level=info msg="StartContainer for \"914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776\" returns successfully" Sep 4 00:54:44.561166 kubelet[1732]: E0904 00:54:44.561009 1732 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" Sep 4 00:54:44.579037 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776-rootfs.mount: Deactivated successfully. Sep 4 00:54:44.583218 kubelet[1732]: I0904 00:54:44.583169 1732 kubelet_node_status.go:431] "Fast updating node status as it just became ready" Sep 4 00:54:44.605818 kubelet[1732]: E0904 00:54:44.605673 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:44.650511 containerd[1464]: time="2026-09-04T00:54:44.650324973Z" level=info msg="shim disconnected" id=914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776 namespace=k8s.io Sep 4 00:54:44.650511 containerd[1464]: time="2026-09-04T00:54:44.650396955Z" level=warning msg="cleaning up after shim disconnected" id=914550699fd04049096e2f91503c34e5735e4a7e538773586ac882a83a326776 namespace=k8s.io Sep 4 00:54:44.650511 containerd[1464]: time="2026-09-04T00:54:44.650408054Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 4 00:54:44.708156 kubelet[1732]: E0904 00:54:44.708024 1732 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" Sep 4 00:54:44.712785 containerd[1464]: time="2026-09-04T00:54:44.712648526Z" level=info msg="CreateContainer within sandbox \"57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 4 00:54:44.725546 containerd[1464]: time="2026-09-04T00:54:44.725258416Z" level=info msg="CreateContainer within sandbox \"57e965995c1790b810f997d40170397ef4d30d4fbbf051a05d8dab982e237632\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"7e42b9a506bbf227a04e949c8696adca989170926a6eae84989bfc8de122280d\"" Sep 4 00:54:44.726567 containerd[1464]: time="2026-09-04T00:54:44.726535344Z" level=info msg="StartContainer for \"7e42b9a506bbf227a04e949c8696adca989170926a6eae84989bfc8de122280d\"" Sep 4 00:54:44.756222 systemd[1]: Started cri-containerd-7e42b9a506bbf227a04e949c8696adca989170926a6eae84989bfc8de122280d.scope - libcontainer container 7e42b9a506bbf227a04e949c8696adca989170926a6eae84989bfc8de122280d. Sep 4 00:54:44.779441 containerd[1464]: time="2026-09-04T00:54:44.779299084Z" level=info msg="StartContainer for \"7e42b9a506bbf227a04e949c8696adca989170926a6eae84989bfc8de122280d\" returns successfully" Sep 4 00:54:44.784374 kubelet[1732]: E0904 00:54:44.784224 1732 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" Sep 4 00:54:44.803806 kubelet[1732]: I0904 00:54:44.803109 1732 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-gkh4v" podStartSLOduration=3.845270428 podStartE2EDuration="7.803037283s" totalImagesPullingTime="3.957766855s" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-09-04 00:54:37 +0000 UTC" imagePullSessionsCount=2 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-04 00:54:44.802989857 +0000 UTC m=+7.482592997" watchObservedRunningTime="2026-09-04 00:54:44.803037283 +0000 UTC m=+7.482640416" Sep 4 00:54:45.606487 kubelet[1732]: E0904 00:54:45.606221 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:45.709231 kubelet[1732]: E0904 00:54:45.709100 1732 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" Sep 4 00:54:45.841723 systemd-networkd[1405]: flannel.1: Link UP Sep 4 00:54:45.841730 systemd-networkd[1405]: flannel.1: Gained carrier Sep 4 00:54:46.607255 kubelet[1732]: E0904 00:54:46.607151 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:47.607747 kubelet[1732]: E0904 00:54:47.607502 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:47.760396 systemd-networkd[1405]: flannel.1: Gained IPv6LL Sep 4 00:54:48.541860 systemd[1]: Created slice kubepods-besteffort-podda1aaba0_ca07_4c8e_ad96_6ac3f42cfab9.slice - libcontainer container kubepods-besteffort-podda1aaba0_ca07_4c8e_ad96_6ac3f42cfab9.slice. Sep 4 00:54:48.600437 kubelet[1732]: I0904 00:54:48.600334 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-q7vfq\" (UniqueName: \"kubernetes.io/projected/da1aaba0-ca07-4c8e-ad96-6ac3f42cfab9-kube-api-access-q7vfq\") pod \"nginx-deployment-85fb466b-x2hkr\" (UID: \"da1aaba0-ca07-4c8e-ad96-6ac3f42cfab9\") " pod="default/nginx-deployment-85fb466b-x2hkr" Sep 4 00:54:48.609160 kubelet[1732]: E0904 00:54:48.608998 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:48.848900 containerd[1464]: time="2026-09-04T00:54:48.848367166Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85fb466b-x2hkr,Uid:da1aaba0-ca07-4c8e-ad96-6ac3f42cfab9,Namespace:default,Attempt:0,}" Sep 4 00:54:48.868696 systemd-networkd[1405]: cni0: Link UP Sep 4 00:54:48.868702 systemd-networkd[1405]: cni0: Gained carrier Sep 4 00:54:48.872083 systemd-networkd[1405]: cni0: Lost carrier Sep 4 00:54:48.876571 systemd-networkd[1405]: veth2d16f183: Link UP Sep 4 00:54:48.878874 kernel: cni0: port 1(veth2d16f183) entered blocking state Sep 4 00:54:48.878956 kernel: cni0: port 1(veth2d16f183) entered disabled state Sep 4 00:54:48.881874 kernel: veth2d16f183: entered allmulticast mode Sep 4 00:54:48.881925 kernel: veth2d16f183: entered promiscuous mode Sep 4 00:54:48.883813 kernel: cni0: port 1(veth2d16f183) entered blocking state Sep 4 00:54:48.883853 kernel: cni0: port 1(veth2d16f183) entered forwarding state Sep 4 00:54:48.886812 kernel: cni0: port 1(veth2d16f183) entered disabled state Sep 4 00:54:48.890678 kernel: cni0: port 1(veth2d16f183) entered blocking state Sep 4 00:54:48.890717 kernel: cni0: port 1(veth2d16f183) entered forwarding state Sep 4 00:54:48.891562 systemd-networkd[1405]: veth2d16f183: Gained carrier Sep 4 00:54:48.892330 systemd-networkd[1405]: cni0: Gained carrier Sep 4 00:54:48.895013 containerd[1464]: 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)(0xc00009a950), "name":"cbr0", "type":"bridge"} Sep 4 00:54:48.895013 containerd[1464]: delegateAdd: netconf sent to delegate plugin: Sep 4 00:54:48.915121 containerd[1464]: {"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-09-04T00:54:48.915033855Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 00:54:48.915814 containerd[1464]: time="2026-09-04T00:54:48.915100431Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 00:54:48.916165 containerd[1464]: time="2026-09-04T00:54:48.916042878Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:48.916369 containerd[1464]: time="2026-09-04T00:54:48.916344694Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:48.936960 systemd[1]: Started cri-containerd-6989ce99a7458c48602ae341bcfc4a0fae1d10100b42e6a0c68027b2e7f7db2e.scope - libcontainer container 6989ce99a7458c48602ae341bcfc4a0fae1d10100b42e6a0c68027b2e7f7db2e. Sep 4 00:54:48.945992 systemd-resolved[1338]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 00:54:48.967668 containerd[1464]: time="2026-09-04T00:54:48.967629287Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85fb466b-x2hkr,Uid:da1aaba0-ca07-4c8e-ad96-6ac3f42cfab9,Namespace:default,Attempt:0,} returns sandbox id \"6989ce99a7458c48602ae341bcfc4a0fae1d10100b42e6a0c68027b2e7f7db2e\"" Sep 4 00:54:48.969112 containerd[1464]: time="2026-09-04T00:54:48.969083275Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 00:54:49.609427 kubelet[1732]: E0904 00:54:49.609265 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:50.513183 systemd-networkd[1405]: veth2d16f183: Gained IPv6LL Sep 4 00:54:50.610208 kubelet[1732]: E0904 00:54:50.610152 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:50.865049 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1077409443.mount: Deactivated successfully. Sep 4 00:54:50.896071 systemd-networkd[1405]: cni0: Gained IPv6LL Sep 4 00:54:51.451484 containerd[1464]: time="2026-09-04T00:54:51.451338638Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:51.451959 containerd[1464]: time="2026-09-04T00:54:51.451817841Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=64128731" Sep 4 00:54:51.453148 containerd[1464]: time="2026-09-04T00:54:51.453080391Z" level=info msg="ImageCreate event name:\"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:51.456605 containerd[1464]: time="2026-09-04T00:54:51.456504828Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:922a5603984560970f4e265613fdc25077f4826efbaf4a082670f2a86a3849f0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:51.457967 containerd[1464]: time="2026-09-04T00:54:51.457918499Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:922a5603984560970f4e265613fdc25077f4826efbaf4a082670f2a86a3849f0\", size \"64128609\" in 2.488803149s" Sep 4 00:54:51.457967 containerd[1464]: time="2026-09-04T00:54:51.457959930Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\"" Sep 4 00:54:51.466158 containerd[1464]: time="2026-09-04T00:54:51.466048069Z" level=info msg="CreateContainer within sandbox \"6989ce99a7458c48602ae341bcfc4a0fae1d10100b42e6a0c68027b2e7f7db2e\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 4 00:54:51.486470 containerd[1464]: time="2026-09-04T00:54:51.486366408Z" level=info msg="CreateContainer within sandbox \"6989ce99a7458c48602ae341bcfc4a0fae1d10100b42e6a0c68027b2e7f7db2e\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"552953e4024ec9cefc5132db9d691d7aa5a741036e90e8a818e1d95c2a77b3b3\"" Sep 4 00:54:51.488156 containerd[1464]: time="2026-09-04T00:54:51.488096199Z" level=info msg="StartContainer for \"552953e4024ec9cefc5132db9d691d7aa5a741036e90e8a818e1d95c2a77b3b3\"" Sep 4 00:54:51.520319 systemd[1]: run-containerd-runc-k8s.io-552953e4024ec9cefc5132db9d691d7aa5a741036e90e8a818e1d95c2a77b3b3-runc.2dl639.mount: Deactivated successfully. Sep 4 00:54:51.541292 systemd[1]: Started cri-containerd-552953e4024ec9cefc5132db9d691d7aa5a741036e90e8a818e1d95c2a77b3b3.scope - libcontainer container 552953e4024ec9cefc5132db9d691d7aa5a741036e90e8a818e1d95c2a77b3b3. Sep 4 00:54:51.583522 containerd[1464]: time="2026-09-04T00:54:51.583357772Z" level=info msg="StartContainer for \"552953e4024ec9cefc5132db9d691d7aa5a741036e90e8a818e1d95c2a77b3b3\" returns successfully" Sep 4 00:54:51.611961 kubelet[1732]: E0904 00:54:51.611498 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:51.626526 kubelet[1732]: I0904 00:54:51.626292 1732 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="default/nginx-deployment-85fb466b-x2hkr" podStartSLOduration=1.134960309 podStartE2EDuration="3.626239011s" totalImagesPullingTime="2.491278702s" totalInitContainerRuntime="0s" isStatefulPod=false podCreationTimestamp="2026-09-04 00:54:48 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-04 00:54:51.626061214 +0000 UTC m=+14.305664377" watchObservedRunningTime="2026-09-04 00:54:51.626239011 +0000 UTC m=+14.305842154" Sep 4 00:54:52.612156 kubelet[1732]: E0904 00:54:52.611985 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:53.613118 kubelet[1732]: E0904 00:54:53.612847 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:54.613815 kubelet[1732]: E0904 00:54:54.613743 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:54.619470 systemd[1]: Created slice kubepods-besteffort-poda474e449_1f69_498b_900c_e4bc307b3d0b.slice - libcontainer container kubepods-besteffort-poda474e449_1f69_498b_900c_e4bc307b3d0b.slice. Sep 4 00:54:54.750337 kubelet[1732]: I0904 00:54:54.750134 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/a474e449-1f69-498b-900c-e4bc307b3d0b-data\") pod \"nfs-server-provisioner-0\" (UID: \"a474e449-1f69-498b-900c-e4bc307b3d0b\") " pod="default/nfs-server-provisioner-0" Sep 4 00:54:54.750703 kubelet[1732]: I0904 00:54:54.750569 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nnfcg\" (UniqueName: \"kubernetes.io/projected/a474e449-1f69-498b-900c-e4bc307b3d0b-kube-api-access-nnfcg\") pod \"nfs-server-provisioner-0\" (UID: \"a474e449-1f69-498b-900c-e4bc307b3d0b\") " pod="default/nfs-server-provisioner-0" Sep 4 00:54:54.928696 containerd[1464]: time="2026-09-04T00:54:54.928107245Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a474e449-1f69-498b-900c-e4bc307b3d0b,Namespace:default,Attempt:0,}" Sep 4 00:54:54.957238 systemd-networkd[1405]: veth778be3ac: Link UP Sep 4 00:54:54.959896 kernel: cni0: port 2(veth778be3ac) entered blocking state Sep 4 00:54:54.959978 kernel: cni0: port 2(veth778be3ac) entered disabled state Sep 4 00:54:54.961196 kernel: veth778be3ac: entered allmulticast mode Sep 4 00:54:54.962128 kernel: veth778be3ac: entered promiscuous mode Sep 4 00:54:54.970864 kernel: cni0: port 2(veth778be3ac) entered blocking state Sep 4 00:54:54.970934 kernel: cni0: port 2(veth778be3ac) entered forwarding state Sep 4 00:54:54.970980 systemd-networkd[1405]: veth778be3ac: Gained carrier Sep 4 00:54:54.973410 containerd[1464]: 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)(0xc0001847f0), "name":"cbr0", "type":"bridge"} Sep 4 00:54:54.973410 containerd[1464]: delegateAdd: netconf sent to delegate plugin: Sep 4 00:54:55.039637 containerd[1464]: {"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-09-04T00:54:55.039243255Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 00:54:55.039637 containerd[1464]: time="2026-09-04T00:54:55.039312601Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 00:54:55.039637 containerd[1464]: time="2026-09-04T00:54:55.039322306Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:55.039637 containerd[1464]: time="2026-09-04T00:54:55.039398843Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:54:55.057984 systemd[1]: Started cri-containerd-826c3e7f903451f43610bc832a22266e9649fec2550ade9f9873d4cb1a80436d.scope - libcontainer container 826c3e7f903451f43610bc832a22266e9649fec2550ade9f9873d4cb1a80436d. Sep 4 00:54:55.066199 systemd-resolved[1338]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 00:54:55.087965 containerd[1464]: time="2026-09-04T00:54:55.087930753Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a474e449-1f69-498b-900c-e4bc307b3d0b,Namespace:default,Attempt:0,} returns sandbox id \"826c3e7f903451f43610bc832a22266e9649fec2550ade9f9873d4cb1a80436d\"" Sep 4 00:54:55.089684 containerd[1464]: time="2026-09-04T00:54:55.089661302Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 4 00:54:55.614669 kubelet[1732]: E0904 00:54:55.614509 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:56.145221 systemd-networkd[1405]: veth778be3ac: Gained IPv6LL Sep 4 00:54:56.622646 kubelet[1732]: E0904 00:54:56.619478 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:57.601216 kubelet[1732]: E0904 00:54:57.601022 1732 file.go:103] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:57.623605 kubelet[1732]: E0904 00:54:57.623274 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:57.687654 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount773125784.mount: Deactivated successfully. Sep 4 00:54:58.624120 kubelet[1732]: E0904 00:54:58.623888 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:54:59.295892 containerd[1464]: time="2026-09-04T00:54:59.295814327Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:59.296363 containerd[1464]: time="2026-09-04T00:54:59.296302221Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039378" Sep 4 00:54:59.297129 containerd[1464]: time="2026-09-04T00:54:59.297085017Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:59.299873 containerd[1464]: time="2026-09-04T00:54:59.299794942Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:54:59.300746 containerd[1464]: time="2026-09-04T00:54:59.300701725Z" 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 4.211012519s" Sep 4 00:54:59.300875 containerd[1464]: time="2026-09-04T00:54:59.300750558Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Sep 4 00:54:59.307339 containerd[1464]: time="2026-09-04T00:54:59.307292702Z" level=info msg="CreateContainer within sandbox \"826c3e7f903451f43610bc832a22266e9649fec2550ade9f9873d4cb1a80436d\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 4 00:54:59.319344 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount725763640.mount: Deactivated successfully. Sep 4 00:54:59.322648 containerd[1464]: time="2026-09-04T00:54:59.322585777Z" level=info msg="CreateContainer within sandbox \"826c3e7f903451f43610bc832a22266e9649fec2550ade9f9873d4cb1a80436d\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"373d3b8c538c8145c45a6b55638ec25a2ca56387d362d411d0aeaf9962b9ae87\"" Sep 4 00:54:59.323675 containerd[1464]: time="2026-09-04T00:54:59.323651321Z" level=info msg="StartContainer for \"373d3b8c538c8145c45a6b55638ec25a2ca56387d362d411d0aeaf9962b9ae87\"" Sep 4 00:54:59.358084 systemd[1]: Started cri-containerd-373d3b8c538c8145c45a6b55638ec25a2ca56387d362d411d0aeaf9962b9ae87.scope - libcontainer container 373d3b8c538c8145c45a6b55638ec25a2ca56387d362d411d0aeaf9962b9ae87. Sep 4 00:54:59.381567 containerd[1464]: time="2026-09-04T00:54:59.381493499Z" level=info msg="StartContainer for \"373d3b8c538c8145c45a6b55638ec25a2ca56387d362d411d0aeaf9962b9ae87\" returns successfully" Sep 4 00:54:59.406489 kubelet[1732]: I0904 00:54:59.406241 1732 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.192940904 podStartE2EDuration="5.406138793s" totalImagesPullingTime="4.213197889s" totalInitContainerRuntime="0s" isStatefulPod=false podCreationTimestamp="2026-09-04 00:54:54 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-04 00:54:59.405943643 +0000 UTC m=+22.085546771" watchObservedRunningTime="2026-09-04 00:54:59.406138793 +0000 UTC m=+22.085741929" Sep 4 00:54:59.625490 kubelet[1732]: E0904 00:54:59.625165 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:00.626411 kubelet[1732]: E0904 00:55:00.626178 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:01.627674 kubelet[1732]: E0904 00:55:01.627443 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:02.628986 kubelet[1732]: E0904 00:55:02.628718 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:03.629820 kubelet[1732]: E0904 00:55:03.629677 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:04.631890 kubelet[1732]: E0904 00:55:04.631579 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:04.790878 systemd[1]: Created slice kubepods-besteffort-poddd598687_a9e7_4ad5_95d6_e3959515c7b7.slice - libcontainer container kubepods-besteffort-poddd598687_a9e7_4ad5_95d6_e3959515c7b7.slice. Sep 4 00:55:04.959329 kubelet[1732]: I0904 00:55:04.958076 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jwj88\" (UniqueName: \"kubernetes.io/projected/dd598687-a9e7-4ad5-95d6-e3959515c7b7-kube-api-access-jwj88\") pod \"test-pod-1\" (UID: \"dd598687-a9e7-4ad5-95d6-e3959515c7b7\") " pod="default/test-pod-1" Sep 4 00:55:04.959329 kubelet[1732]: I0904 00:55:04.958403 1732 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-dfcabd92-bf09-4ed3-b28c-773789c60c12\" (UniqueName: \"kubernetes.io/nfs/dd598687-a9e7-4ad5-95d6-e3959515c7b7-pvc-dfcabd92-bf09-4ed3-b28c-773789c60c12\") pod \"test-pod-1\" (UID: \"dd598687-a9e7-4ad5-95d6-e3959515c7b7\") " pod="default/test-pod-1" Sep 4 00:55:05.130229 kernel: FS-Cache: Loaded Sep 4 00:55:05.252904 kernel: RPC: Registered named UNIX socket transport module. Sep 4 00:55:05.253072 kernel: RPC: Registered udp transport module. Sep 4 00:55:05.253101 kernel: RPC: Registered tcp transport module. Sep 4 00:55:05.254434 kernel: RPC: Registered tcp-with-tls transport module. Sep 4 00:55:05.255645 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 4 00:55:05.633604 kubelet[1732]: E0904 00:55:05.633344 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:05.711205 kernel: NFS: Registering the id_resolver key type Sep 4 00:55:05.711364 kernel: Key type id_resolver registered Sep 4 00:55:05.711387 kernel: Key type id_legacy registered Sep 4 00:55:05.784811 nfsidmap[2737]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 00:55:05.795737 nfsidmap[2740]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 00:55:06.005836 containerd[1464]: time="2026-09-04T00:55:06.004007448Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:dd598687-a9e7-4ad5-95d6-e3959515c7b7,Namespace:default,Attempt:0,}" Sep 4 00:55:06.067663 systemd-networkd[1405]: veth511a8fd8: Link UP Sep 4 00:55:06.072539 kernel: cni0: port 3(veth511a8fd8) entered blocking state Sep 4 00:55:06.072632 kernel: cni0: port 3(veth511a8fd8) entered disabled state Sep 4 00:55:06.072652 kernel: veth511a8fd8: entered allmulticast mode Sep 4 00:55:06.072674 kernel: veth511a8fd8: entered promiscuous mode Sep 4 00:55:06.089960 kernel: cni0: port 3(veth511a8fd8) entered blocking state Sep 4 00:55:06.090067 kernel: cni0: port 3(veth511a8fd8) entered forwarding state Sep 4 00:55:06.089446 systemd-networkd[1405]: veth511a8fd8: Gained carrier Sep 4 00:55:06.105617 containerd[1464]: 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)(0xc0000a2950), "name":"cbr0", "type":"bridge"} Sep 4 00:55:06.105617 containerd[1464]: delegateAdd: netconf sent to delegate plugin: Sep 4 00:55:06.169538 containerd[1464]: {"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-09-04T00:55:06.169352308Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 4 00:55:06.169538 containerd[1464]: time="2026-09-04T00:55:06.169495822Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 4 00:55:06.169538 containerd[1464]: time="2026-09-04T00:55:06.169528911Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:55:06.169794 containerd[1464]: time="2026-09-04T00:55:06.169639847Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 4 00:55:06.199063 systemd[1]: Started cri-containerd-023720fd18d65ca38a77b4314d6522650d3339aa0395b754db66ab3e276005bf.scope - libcontainer container 023720fd18d65ca38a77b4314d6522650d3339aa0395b754db66ab3e276005bf. Sep 4 00:55:06.215849 systemd-resolved[1338]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 00:55:06.251352 containerd[1464]: time="2026-09-04T00:55:06.251238808Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:dd598687-a9e7-4ad5-95d6-e3959515c7b7,Namespace:default,Attempt:0,} returns sandbox id \"023720fd18d65ca38a77b4314d6522650d3339aa0395b754db66ab3e276005bf\"" Sep 4 00:55:06.254100 containerd[1464]: time="2026-09-04T00:55:06.253753910Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 00:55:06.634732 kubelet[1732]: E0904 00:55:06.634312 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:06.670418 containerd[1464]: time="2026-09-04T00:55:06.670279333Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 00:55:06.672934 containerd[1464]: time="2026-09-04T00:55:06.672197181Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 4 00:55:06.676979 containerd[1464]: time="2026-09-04T00:55:06.676749412Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:922a5603984560970f4e265613fdc25077f4826efbaf4a082670f2a86a3849f0\", size \"64128609\" in 422.880626ms" Sep 4 00:55:06.676979 containerd[1464]: time="2026-09-04T00:55:06.676862572Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\"" Sep 4 00:55:06.695885 containerd[1464]: time="2026-09-04T00:55:06.689843917Z" level=info msg="CreateContainer within sandbox \"023720fd18d65ca38a77b4314d6522650d3339aa0395b754db66ab3e276005bf\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 4 00:55:06.729798 containerd[1464]: time="2026-09-04T00:55:06.729477539Z" level=info msg="CreateContainer within sandbox \"023720fd18d65ca38a77b4314d6522650d3339aa0395b754db66ab3e276005bf\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"ccd2e8ea00f77e179896124e491f4dbf2241207a329f90b842ec526119834999\"" Sep 4 00:55:06.735822 containerd[1464]: time="2026-09-04T00:55:06.732074090Z" level=info msg="StartContainer for \"ccd2e8ea00f77e179896124e491f4dbf2241207a329f90b842ec526119834999\"" Sep 4 00:55:06.809920 systemd[1]: Started cri-containerd-ccd2e8ea00f77e179896124e491f4dbf2241207a329f90b842ec526119834999.scope - libcontainer container ccd2e8ea00f77e179896124e491f4dbf2241207a329f90b842ec526119834999. Sep 4 00:55:06.913483 containerd[1464]: time="2026-09-04T00:55:06.913217697Z" level=info msg="StartContainer for \"ccd2e8ea00f77e179896124e491f4dbf2241207a329f90b842ec526119834999\" returns successfully" Sep 4 00:55:06.988178 kubelet[1732]: I0904 00:55:06.987412 1732 pod_startup_latency_tracker.go:148] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=12.561265599 podStartE2EDuration="12.987386903s" totalImagesPullingTime="426.121304ms" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-09-04 00:54:54 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-04 00:55:06.987116049 +0000 UTC m=+29.666719184" watchObservedRunningTime="2026-09-04 00:55:06.987386903 +0000 UTC m=+29.666990043" Sep 4 00:55:07.635380 kubelet[1732]: E0904 00:55:07.635035 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:07.856321 systemd-networkd[1405]: veth511a8fd8: Gained IPv6LL Sep 4 00:55:08.636200 kubelet[1732]: E0904 00:55:08.635902 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:09.637265 kubelet[1732]: E0904 00:55:09.637154 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:10.638585 kubelet[1732]: E0904 00:55:10.637691 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:11.640033 kubelet[1732]: E0904 00:55:11.638489 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 00:55:12.641572 kubelet[1732]: E0904 00:55:12.641275 1732 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"