Sep 11 00:24:55.959956 kernel: Linux version 6.12.46-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Sep 10 22:25:29 -00 2025 Sep 11 00:24:55.959989 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:24:55.960000 kernel: BIOS-provided physical RAM map: Sep 11 00:24:55.960007 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 11 00:24:55.960013 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 11 00:24:55.960020 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 11 00:24:55.960028 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdafff] usable Sep 11 00:24:55.960047 kernel: BIOS-e820: [mem 0x000000007ffdb000-0x000000007fffffff] reserved Sep 11 00:24:55.960063 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 11 00:24:55.960072 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 11 00:24:55.960081 kernel: NX (Execute Disable) protection: active Sep 11 00:24:55.960090 kernel: APIC: Static calls initialized Sep 11 00:24:55.960100 kernel: SMBIOS 2.8 present. Sep 11 00:24:55.960110 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 Sep 11 00:24:55.960127 kernel: DMI: Memory slots populated: 1/1 Sep 11 00:24:55.960137 kernel: Hypervisor detected: KVM Sep 11 00:24:55.960153 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 11 00:24:55.960164 kernel: kvm-clock: using sched offset of 5292878305 cycles Sep 11 00:24:55.960176 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 11 00:24:55.960187 kernel: tsc: Detected 1995.309 MHz processor Sep 11 00:24:55.960197 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 11 00:24:55.960205 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 11 00:24:55.960215 kernel: last_pfn = 0x7ffdb max_arch_pfn = 0x400000000 Sep 11 00:24:55.960223 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 11 00:24:55.960231 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 11 00:24:55.960238 kernel: ACPI: Early table checksum verification disabled Sep 11 00:24:55.960248 kernel: ACPI: RSDP 0x00000000000F5950 000014 (v00 BOCHS ) Sep 11 00:24:55.960263 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:24:55.960275 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:24:55.960285 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:24:55.960296 kernel: ACPI: FACS 0x000000007FFE0000 000040 Sep 11 00:24:55.960310 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:24:55.960322 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:24:55.960332 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:24:55.960343 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:24:55.960355 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] Sep 11 00:24:55.960366 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] Sep 11 00:24:55.960377 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Sep 11 00:24:55.960388 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] Sep 11 00:24:55.960400 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] Sep 11 00:24:55.960419 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] Sep 11 00:24:55.960431 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] Sep 11 00:24:55.960444 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Sep 11 00:24:55.960456 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Sep 11 00:24:55.960463 kernel: NUMA: Node 0 [mem 0x00001000-0x0009ffff] + [mem 0x00100000-0x7ffdafff] -> [mem 0x00001000-0x7ffdafff] Sep 11 00:24:55.960474 kernel: NODE_DATA(0) allocated [mem 0x7ffd3dc0-0x7ffdafff] Sep 11 00:24:55.960486 kernel: Zone ranges: Sep 11 00:24:55.960499 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 11 00:24:55.960512 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdafff] Sep 11 00:24:55.960526 kernel: Normal empty Sep 11 00:24:55.960534 kernel: Device empty Sep 11 00:24:55.960542 kernel: Movable zone start for each node Sep 11 00:24:55.960550 kernel: Early memory node ranges Sep 11 00:24:55.960557 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 11 00:24:55.960565 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdafff] Sep 11 00:24:55.960576 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdafff] Sep 11 00:24:55.960584 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 11 00:24:55.960591 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 11 00:24:55.960599 kernel: On node 0, zone DMA32: 37 pages in unavailable ranges Sep 11 00:24:55.960607 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 11 00:24:55.960614 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 11 00:24:55.960628 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 11 00:24:55.960636 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 11 00:24:55.960647 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 11 00:24:55.960658 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 11 00:24:55.960669 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 11 00:24:55.960677 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 11 00:24:55.960685 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 11 00:24:55.960692 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 11 00:24:55.960700 kernel: TSC deadline timer available Sep 11 00:24:55.960708 kernel: CPU topo: Max. logical packages: 1 Sep 11 00:24:55.960715 kernel: CPU topo: Max. logical dies: 1 Sep 11 00:24:55.960723 kernel: CPU topo: Max. dies per package: 1 Sep 11 00:24:55.960733 kernel: CPU topo: Max. threads per core: 1 Sep 11 00:24:55.960741 kernel: CPU topo: Num. cores per package: 2 Sep 11 00:24:55.960749 kernel: CPU topo: Num. threads per package: 2 Sep 11 00:24:55.960756 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Sep 11 00:24:55.960764 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 11 00:24:55.960772 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Sep 11 00:24:55.960780 kernel: Booting paravirtualized kernel on KVM Sep 11 00:24:55.960788 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 11 00:24:55.960796 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Sep 11 00:24:55.960806 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576 Sep 11 00:24:55.960814 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152 Sep 11 00:24:55.960821 kernel: pcpu-alloc: [0] 0 1 Sep 11 00:24:55.960829 kernel: kvm-guest: PV spinlocks disabled, no host support Sep 11 00:24:55.960838 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:24:55.960849 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 11 00:24:55.960863 kernel: random: crng init done Sep 11 00:24:55.962965 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 11 00:24:55.962989 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 11 00:24:55.963004 kernel: Fallback order for Node 0: 0 Sep 11 00:24:55.963017 kernel: Built 1 zonelists, mobility grouping on. Total pages: 524153 Sep 11 00:24:55.963031 kernel: Policy zone: DMA32 Sep 11 00:24:55.963045 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 11 00:24:55.963059 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 11 00:24:55.963073 kernel: Kernel/User page tables isolation: enabled Sep 11 00:24:55.963086 kernel: ftrace: allocating 40103 entries in 157 pages Sep 11 00:24:55.963100 kernel: ftrace: allocated 157 pages with 5 groups Sep 11 00:24:55.963117 kernel: Dynamic Preempt: voluntary Sep 11 00:24:55.963131 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 11 00:24:55.963147 kernel: rcu: RCU event tracing is enabled. Sep 11 00:24:55.963161 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 11 00:24:55.963175 kernel: Trampoline variant of Tasks RCU enabled. Sep 11 00:24:55.963189 kernel: Rude variant of Tasks RCU enabled. Sep 11 00:24:55.963202 kernel: Tracing variant of Tasks RCU enabled. Sep 11 00:24:55.963214 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 11 00:24:55.963227 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 11 00:24:55.963243 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 11 00:24:55.963263 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 11 00:24:55.963271 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 11 00:24:55.963279 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Sep 11 00:24:55.963288 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 11 00:24:55.963295 kernel: Console: colour VGA+ 80x25 Sep 11 00:24:55.963303 kernel: printk: legacy console [tty0] enabled Sep 11 00:24:55.963311 kernel: printk: legacy console [ttyS0] enabled Sep 11 00:24:55.963319 kernel: ACPI: Core revision 20240827 Sep 11 00:24:55.963331 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 11 00:24:55.963348 kernel: APIC: Switch to symmetric I/O mode setup Sep 11 00:24:55.963356 kernel: x2apic enabled Sep 11 00:24:55.963367 kernel: APIC: Switched APIC routing to: physical x2apic Sep 11 00:24:55.963376 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 11 00:24:55.963389 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3985bd6d44e, max_idle_ns: 881590467931 ns Sep 11 00:24:55.963398 kernel: Calibrating delay loop (skipped) preset value.. 3990.61 BogoMIPS (lpj=1995309) Sep 11 00:24:55.963410 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 11 00:24:55.963423 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 11 00:24:55.963436 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 11 00:24:55.963453 kernel: Spectre V2 : Mitigation: Retpolines Sep 11 00:24:55.963468 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 11 00:24:55.963482 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Sep 11 00:24:55.963496 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 11 00:24:55.963509 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 11 00:24:55.963524 kernel: MDS: Mitigation: Clear CPU buffers Sep 11 00:24:55.963540 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 11 00:24:55.963558 kernel: active return thunk: its_return_thunk Sep 11 00:24:55.963571 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 11 00:24:55.963586 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 11 00:24:55.963600 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 11 00:24:55.963615 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 11 00:24:55.963630 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 11 00:24:55.963643 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 11 00:24:55.963652 kernel: Freeing SMP alternatives memory: 32K Sep 11 00:24:55.963664 kernel: pid_max: default: 32768 minimum: 301 Sep 11 00:24:55.963673 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 11 00:24:55.963682 kernel: landlock: Up and running. Sep 11 00:24:55.963690 kernel: SELinux: Initializing. Sep 11 00:24:55.963699 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Sep 11 00:24:55.963708 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Sep 11 00:24:55.963716 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) Sep 11 00:24:55.963725 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 11 00:24:55.963734 kernel: signal: max sigframe size: 1776 Sep 11 00:24:55.963745 kernel: rcu: Hierarchical SRCU implementation. Sep 11 00:24:55.963754 kernel: rcu: Max phase no-delay instances is 400. Sep 11 00:24:55.963763 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 11 00:24:55.963771 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 11 00:24:55.963780 kernel: smp: Bringing up secondary CPUs ... Sep 11 00:24:55.963798 kernel: smpboot: x86: Booting SMP configuration: Sep 11 00:24:55.963813 kernel: .... node #0, CPUs: #1 Sep 11 00:24:55.963827 kernel: smp: Brought up 1 node, 2 CPUs Sep 11 00:24:55.963842 kernel: smpboot: Total of 2 processors activated (7981.23 BogoMIPS) Sep 11 00:24:55.963858 kernel: Memory: 1968964K/2096612K available (14336K kernel code, 2429K rwdata, 9960K rodata, 53832K init, 1088K bss, 123092K reserved, 0K cma-reserved) Sep 11 00:24:55.964523 kernel: devtmpfs: initialized Sep 11 00:24:55.964541 kernel: x86/mm: Memory block size: 128MB Sep 11 00:24:55.964553 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 11 00:24:55.964567 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 11 00:24:55.964580 kernel: pinctrl core: initialized pinctrl subsystem Sep 11 00:24:55.964593 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 11 00:24:55.964606 kernel: audit: initializing netlink subsys (disabled) Sep 11 00:24:55.964618 kernel: audit: type=2000 audit(1757550291.757:1): state=initialized audit_enabled=0 res=1 Sep 11 00:24:55.964639 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 11 00:24:55.964652 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 11 00:24:55.964665 kernel: cpuidle: using governor menu Sep 11 00:24:55.964679 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 11 00:24:55.964690 kernel: dca service started, version 1.12.1 Sep 11 00:24:55.964702 kernel: PCI: Using configuration type 1 for base access Sep 11 00:24:55.964715 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 11 00:24:55.964727 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 11 00:24:55.964739 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 11 00:24:55.964757 kernel: ACPI: Added _OSI(Module Device) Sep 11 00:24:55.964769 kernel: ACPI: Added _OSI(Processor Device) Sep 11 00:24:55.964783 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 11 00:24:55.964796 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 11 00:24:55.964808 kernel: ACPI: Interpreter enabled Sep 11 00:24:55.964820 kernel: ACPI: PM: (supports S0 S5) Sep 11 00:24:55.964833 kernel: ACPI: Using IOAPIC for interrupt routing Sep 11 00:24:55.964846 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 11 00:24:55.964859 kernel: PCI: Using E820 reservations for host bridge windows Sep 11 00:24:55.966939 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Sep 11 00:24:55.966968 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 11 00:24:55.967281 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Sep 11 00:24:55.967452 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Sep 11 00:24:55.967554 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Sep 11 00:24:55.967566 kernel: acpiphp: Slot [3] registered Sep 11 00:24:55.967575 kernel: acpiphp: Slot [4] registered Sep 11 00:24:55.967584 kernel: acpiphp: Slot [5] registered Sep 11 00:24:55.967597 kernel: acpiphp: Slot [6] registered Sep 11 00:24:55.967606 kernel: acpiphp: Slot [7] registered Sep 11 00:24:55.967615 kernel: acpiphp: Slot [8] registered Sep 11 00:24:55.967623 kernel: acpiphp: Slot [9] registered Sep 11 00:24:55.967636 kernel: acpiphp: Slot [10] registered Sep 11 00:24:55.967655 kernel: acpiphp: Slot [11] registered Sep 11 00:24:55.967667 kernel: acpiphp: Slot [12] registered Sep 11 00:24:55.967679 kernel: acpiphp: Slot [13] registered Sep 11 00:24:55.967691 kernel: acpiphp: Slot [14] registered Sep 11 00:24:55.967707 kernel: acpiphp: Slot [15] registered Sep 11 00:24:55.967719 kernel: acpiphp: Slot [16] registered Sep 11 00:24:55.967731 kernel: acpiphp: Slot [17] registered Sep 11 00:24:55.967744 kernel: acpiphp: Slot [18] registered Sep 11 00:24:55.967757 kernel: acpiphp: Slot [19] registered Sep 11 00:24:55.967771 kernel: acpiphp: Slot [20] registered Sep 11 00:24:55.967784 kernel: acpiphp: Slot [21] registered Sep 11 00:24:55.967797 kernel: acpiphp: Slot [22] registered Sep 11 00:24:55.967811 kernel: acpiphp: Slot [23] registered Sep 11 00:24:55.967824 kernel: acpiphp: Slot [24] registered Sep 11 00:24:55.967843 kernel: acpiphp: Slot [25] registered Sep 11 00:24:55.967859 kernel: acpiphp: Slot [26] registered Sep 11 00:24:55.967893 kernel: acpiphp: Slot [27] registered Sep 11 00:24:55.967906 kernel: acpiphp: Slot [28] registered Sep 11 00:24:55.967919 kernel: acpiphp: Slot [29] registered Sep 11 00:24:55.967933 kernel: acpiphp: Slot [30] registered Sep 11 00:24:55.967948 kernel: acpiphp: Slot [31] registered Sep 11 00:24:55.967958 kernel: PCI host bridge to bus 0000:00 Sep 11 00:24:55.968142 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 11 00:24:55.968284 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 11 00:24:55.968408 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 11 00:24:55.968494 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Sep 11 00:24:55.968576 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Sep 11 00:24:55.968705 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 11 00:24:55.970936 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint Sep 11 00:24:55.971200 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint Sep 11 00:24:55.971330 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint Sep 11 00:24:55.971473 kernel: pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef] Sep 11 00:24:55.971606 kernel: pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk Sep 11 00:24:55.971736 kernel: pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk Sep 11 00:24:55.971890 kernel: pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk Sep 11 00:24:55.972040 kernel: pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk Sep 11 00:24:55.972220 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint Sep 11 00:24:55.972363 kernel: pci 0000:00:01.2: BAR 4 [io 0xc180-0xc19f] Sep 11 00:24:55.972525 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint Sep 11 00:24:55.972666 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Sep 11 00:24:55.972807 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Sep 11 00:24:55.974176 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 conventional PCI endpoint Sep 11 00:24:55.974362 kernel: pci 0000:00:02.0: BAR 0 [mem 0xfe000000-0xfe7fffff pref] Sep 11 00:24:55.974514 kernel: pci 0000:00:02.0: BAR 2 [mem 0xfe800000-0xfe803fff 64bit pref] Sep 11 00:24:55.974663 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfebf0000-0xfebf0fff] Sep 11 00:24:55.974813 kernel: pci 0000:00:02.0: ROM [mem 0xfebe0000-0xfebeffff pref] Sep 11 00:24:55.976014 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 11 00:24:55.976194 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 11 00:24:55.976360 kernel: pci 0000:00:03.0: BAR 0 [io 0xc1a0-0xc1bf] Sep 11 00:24:55.976512 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebf1000-0xfebf1fff] Sep 11 00:24:55.976666 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe804000-0xfe807fff 64bit pref] Sep 11 00:24:55.976842 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 11 00:24:55.978131 kernel: pci 0000:00:04.0: BAR 0 [io 0xc1c0-0xc1df] Sep 11 00:24:55.978301 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebf2000-0xfebf2fff] Sep 11 00:24:55.978452 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe808000-0xfe80bfff 64bit pref] Sep 11 00:24:55.978575 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 conventional PCI endpoint Sep 11 00:24:55.978680 kernel: pci 0000:00:05.0: BAR 0 [io 0xc100-0xc13f] Sep 11 00:24:55.978774 kernel: pci 0000:00:05.0: BAR 1 [mem 0xfebf3000-0xfebf3fff] Sep 11 00:24:55.979899 kernel: pci 0000:00:05.0: BAR 4 [mem 0xfe80c000-0xfe80ffff 64bit pref] Sep 11 00:24:55.980081 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 11 00:24:55.980179 kernel: pci 0000:00:06.0: BAR 0 [io 0xc000-0xc07f] Sep 11 00:24:55.980273 kernel: pci 0000:00:06.0: BAR 1 [mem 0xfebf4000-0xfebf4fff] Sep 11 00:24:55.980372 kernel: pci 0000:00:06.0: BAR 4 [mem 0xfe810000-0xfe813fff 64bit pref] Sep 11 00:24:55.980479 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 11 00:24:55.980573 kernel: pci 0000:00:07.0: BAR 0 [io 0xc080-0xc0ff] Sep 11 00:24:55.980688 kernel: pci 0000:00:07.0: BAR 1 [mem 0xfebf5000-0xfebf5fff] Sep 11 00:24:55.980820 kernel: pci 0000:00:07.0: BAR 4 [mem 0xfe814000-0xfe817fff 64bit pref] Sep 11 00:24:55.982024 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint Sep 11 00:24:55.982130 kernel: pci 0000:00:08.0: BAR 0 [io 0xc140-0xc17f] Sep 11 00:24:55.982228 kernel: pci 0000:00:08.0: BAR 4 [mem 0xfe818000-0xfe81bfff 64bit pref] Sep 11 00:24:55.982239 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 11 00:24:55.982249 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 11 00:24:55.982258 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 11 00:24:55.982267 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 11 00:24:55.982276 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Sep 11 00:24:55.982284 kernel: iommu: Default domain type: Translated Sep 11 00:24:55.982293 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 11 00:24:55.982304 kernel: PCI: Using ACPI for IRQ routing Sep 11 00:24:55.982313 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 11 00:24:55.982322 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 11 00:24:55.982331 kernel: e820: reserve RAM buffer [mem 0x7ffdb000-0x7fffffff] Sep 11 00:24:55.982426 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Sep 11 00:24:55.982518 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Sep 11 00:24:55.982610 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 11 00:24:55.982621 kernel: vgaarb: loaded Sep 11 00:24:55.982633 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 11 00:24:55.982642 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 11 00:24:55.982651 kernel: clocksource: Switched to clocksource kvm-clock Sep 11 00:24:55.982660 kernel: VFS: Disk quotas dquot_6.6.0 Sep 11 00:24:55.982669 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 11 00:24:55.982677 kernel: pnp: PnP ACPI init Sep 11 00:24:55.982686 kernel: pnp: PnP ACPI: found 4 devices Sep 11 00:24:55.982694 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 11 00:24:55.982703 kernel: NET: Registered PF_INET protocol family Sep 11 00:24:55.982714 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 11 00:24:55.982723 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Sep 11 00:24:55.982731 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 11 00:24:55.982740 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 11 00:24:55.982748 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Sep 11 00:24:55.982757 kernel: TCP: Hash tables configured (established 16384 bind 16384) Sep 11 00:24:55.982766 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Sep 11 00:24:55.982774 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Sep 11 00:24:55.982783 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 11 00:24:55.982794 kernel: NET: Registered PF_XDP protocol family Sep 11 00:24:55.982903 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 11 00:24:55.982989 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 11 00:24:55.983071 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 11 00:24:55.983151 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Sep 11 00:24:55.983233 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Sep 11 00:24:55.983329 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Sep 11 00:24:55.983425 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Sep 11 00:24:55.983442 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Sep 11 00:24:55.983534 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x720 took 44618 usecs Sep 11 00:24:55.983545 kernel: PCI: CLS 0 bytes, default 64 Sep 11 00:24:55.983554 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 11 00:24:55.983563 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3985bd6d44e, max_idle_ns: 881590467931 ns Sep 11 00:24:55.983571 kernel: Initialise system trusted keyrings Sep 11 00:24:55.983580 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Sep 11 00:24:55.983588 kernel: Key type asymmetric registered Sep 11 00:24:55.983597 kernel: Asymmetric key parser 'x509' registered Sep 11 00:24:55.983609 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 11 00:24:55.983618 kernel: io scheduler mq-deadline registered Sep 11 00:24:55.983627 kernel: io scheduler kyber registered Sep 11 00:24:55.983636 kernel: io scheduler bfq registered Sep 11 00:24:55.983644 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 11 00:24:55.983653 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Sep 11 00:24:55.983661 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Sep 11 00:24:55.983670 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Sep 11 00:24:55.983678 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 11 00:24:55.983690 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 11 00:24:55.983698 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 11 00:24:55.983707 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 11 00:24:55.983715 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 11 00:24:55.983859 kernel: rtc_cmos 00:03: RTC can wake from S4 Sep 11 00:24:55.986447 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 11 00:24:55.986578 kernel: rtc_cmos 00:03: registered as rtc0 Sep 11 00:24:55.986666 kernel: rtc_cmos 00:03: setting system clock to 2025-09-11T00:24:55 UTC (1757550295) Sep 11 00:24:55.986772 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Sep 11 00:24:55.986784 kernel: intel_pstate: CPU model not supported Sep 11 00:24:55.986793 kernel: NET: Registered PF_INET6 protocol family Sep 11 00:24:55.986801 kernel: Segment Routing with IPv6 Sep 11 00:24:55.986810 kernel: In-situ OAM (IOAM) with IPv6 Sep 11 00:24:55.986819 kernel: NET: Registered PF_PACKET protocol family Sep 11 00:24:55.986828 kernel: Key type dns_resolver registered Sep 11 00:24:55.986837 kernel: IPI shorthand broadcast: enabled Sep 11 00:24:55.986846 kernel: sched_clock: Marking stable (4501007282, 187587356)->(4724976585, -36381947) Sep 11 00:24:55.986858 kernel: registered taskstats version 1 Sep 11 00:24:55.986883 kernel: Loading compiled-in X.509 certificates Sep 11 00:24:55.986902 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: 8138ce5002a1b572fd22b23ac238f29bab3f249f' Sep 11 00:24:55.986911 kernel: Demotion targets for Node 0: null Sep 11 00:24:55.986920 kernel: Key type .fscrypt registered Sep 11 00:24:55.986929 kernel: Key type fscrypt-provisioning registered Sep 11 00:24:55.986956 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 11 00:24:55.986968 kernel: ima: Allocated hash algorithm: sha1 Sep 11 00:24:55.986979 kernel: ima: No architecture policies found Sep 11 00:24:55.986988 kernel: clk: Disabling unused clocks Sep 11 00:24:55.986997 kernel: Warning: unable to open an initial console. Sep 11 00:24:55.987007 kernel: Freeing unused kernel image (initmem) memory: 53832K Sep 11 00:24:55.987016 kernel: Write protecting the kernel read-only data: 24576k Sep 11 00:24:55.987025 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 11 00:24:55.987034 kernel: Run /init as init process Sep 11 00:24:55.987043 kernel: with arguments: Sep 11 00:24:55.987052 kernel: /init Sep 11 00:24:55.987063 kernel: with environment: Sep 11 00:24:55.987072 kernel: HOME=/ Sep 11 00:24:55.987081 kernel: TERM=linux Sep 11 00:24:55.987090 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 11 00:24:55.987100 systemd[1]: Successfully made /usr/ read-only. Sep 11 00:24:55.987115 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 11 00:24:55.987125 systemd[1]: Detected virtualization kvm. Sep 11 00:24:55.987135 systemd[1]: Detected architecture x86-64. Sep 11 00:24:55.987147 systemd[1]: Running in initrd. Sep 11 00:24:55.987156 systemd[1]: No hostname configured, using default hostname. Sep 11 00:24:55.987165 systemd[1]: Hostname set to . Sep 11 00:24:55.987175 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:24:55.987186 systemd[1]: Queued start job for default target initrd.target. Sep 11 00:24:55.987195 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:24:55.987205 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:24:55.987217 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 11 00:24:55.987229 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:24:55.987239 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 11 00:24:55.987251 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 11 00:24:55.987265 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 11 00:24:55.987274 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 11 00:24:55.987284 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:24:55.987293 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:24:55.987303 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:24:55.987312 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:24:55.987322 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:24:55.987332 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:24:55.987341 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:24:55.987353 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:24:55.987363 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 11 00:24:55.987373 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 11 00:24:55.987382 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:24:55.987391 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:24:55.987401 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:24:55.987410 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:24:55.987419 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 11 00:24:55.987431 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:24:55.987440 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 11 00:24:55.987450 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 11 00:24:55.987460 systemd[1]: Starting systemd-fsck-usr.service... Sep 11 00:24:55.987469 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:24:55.987478 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:24:55.987487 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:24:55.987496 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 11 00:24:55.987516 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:24:55.987562 systemd-journald[212]: Collecting audit messages is disabled. Sep 11 00:24:55.987593 systemd[1]: Finished systemd-fsck-usr.service. Sep 11 00:24:55.987604 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:24:55.987615 systemd-journald[212]: Journal started Sep 11 00:24:55.987639 systemd-journald[212]: Runtime Journal (/run/log/journal/a8e8cbc7caa64a5f8bfc2379642fa437) is 4.9M, max 39.6M, 34.6M free. Sep 11 00:24:55.993370 systemd-modules-load[213]: Inserted module 'overlay' Sep 11 00:24:56.051995 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:24:56.052040 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 11 00:24:56.052064 kernel: Bridge firewalling registered Sep 11 00:24:56.036398 systemd-modules-load[213]: Inserted module 'br_netfilter' Sep 11 00:24:56.052982 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:24:56.054231 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:24:56.055268 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:24:56.058522 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 11 00:24:56.060171 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:24:56.062531 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:24:56.067026 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:24:56.090123 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:24:56.093990 systemd-tmpfiles[229]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 11 00:24:56.097968 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:24:56.103174 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:24:56.107560 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:24:56.115448 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:24:56.118099 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 11 00:24:56.147446 dracut-cmdline[250]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=digitalocean verity.usrhash=24178014e7d1a618b6c727661dc98ca9324f7f5aeefcaa5f4996d4d839e6e63a Sep 11 00:24:56.167179 systemd-resolved[247]: Positive Trust Anchors: Sep 11 00:24:56.167196 systemd-resolved[247]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:24:56.167230 systemd-resolved[247]: 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 11 00:24:56.176477 systemd-resolved[247]: Defaulting to hostname 'linux'. Sep 11 00:24:56.178069 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:24:56.179224 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:24:56.279936 kernel: SCSI subsystem initialized Sep 11 00:24:56.291910 kernel: Loading iSCSI transport class v2.0-870. Sep 11 00:24:56.306899 kernel: iscsi: registered transport (tcp) Sep 11 00:24:56.338069 kernel: iscsi: registered transport (qla4xxx) Sep 11 00:24:56.338197 kernel: QLogic iSCSI HBA Driver Sep 11 00:24:56.371823 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:24:56.393233 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:24:56.398607 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:24:56.466059 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 11 00:24:56.468982 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 11 00:24:56.533926 kernel: raid6: avx2x4 gen() 25473 MB/s Sep 11 00:24:56.550937 kernel: raid6: avx2x2 gen() 24024 MB/s Sep 11 00:24:56.568046 kernel: raid6: avx2x1 gen() 16545 MB/s Sep 11 00:24:56.568141 kernel: raid6: using algorithm avx2x4 gen() 25473 MB/s Sep 11 00:24:56.586308 kernel: raid6: .... xor() 7845 MB/s, rmw enabled Sep 11 00:24:56.586433 kernel: raid6: using avx2x2 recovery algorithm Sep 11 00:24:56.614936 kernel: xor: automatically using best checksumming function avx Sep 11 00:24:56.811923 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 11 00:24:56.820510 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:24:56.824415 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:24:56.854258 systemd-udevd[460]: Using default interface naming scheme 'v255'. Sep 11 00:24:56.860481 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:24:56.864802 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 11 00:24:56.897385 dracut-pre-trigger[468]: rd.md=0: removing MD RAID activation Sep 11 00:24:56.932609 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:24:56.935796 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:24:57.011359 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:24:57.016195 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 11 00:24:57.118188 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues Sep 11 00:24:57.119510 kernel: virtio_scsi virtio3: 2/0/0 default/read/poll queues Sep 11 00:24:57.128896 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Sep 11 00:24:57.131237 kernel: scsi host0: Virtio SCSI HBA Sep 11 00:24:57.153910 kernel: libata version 3.00 loaded. Sep 11 00:24:57.168299 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 11 00:24:57.168422 kernel: GPT:9289727 != 125829119 Sep 11 00:24:57.168444 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 11 00:24:57.168464 kernel: GPT:9289727 != 125829119 Sep 11 00:24:57.168483 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 11 00:24:57.168502 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:24:57.171934 kernel: cryptd: max_cpu_qlen set to 1000 Sep 11 00:24:57.173910 kernel: ACPI: bus type USB registered Sep 11 00:24:57.176928 kernel: usbcore: registered new interface driver usbfs Sep 11 00:24:57.184909 kernel: ata_piix 0000:00:01.1: version 2.13 Sep 11 00:24:57.191915 kernel: AES CTR mode by8 optimization enabled Sep 11 00:24:57.193911 kernel: usbcore: registered new interface driver hub Sep 11 00:24:57.197906 kernel: usbcore: registered new device driver usb Sep 11 00:24:57.201964 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues Sep 11 00:24:57.210095 kernel: virtio_blk virtio5: [vdb] 932 512-byte logical blocks (477 kB/466 KiB) Sep 11 00:24:57.241914 kernel: scsi host1: ata_piix Sep 11 00:24:57.247903 kernel: scsi host2: ata_piix Sep 11 00:24:57.256169 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:24:57.256338 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:24:57.275343 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0 Sep 11 00:24:57.275426 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0 Sep 11 00:24:57.258103 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:24:57.262845 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:24:57.276946 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:24:57.333559 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Sep 11 00:24:57.334182 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Sep 11 00:24:57.334384 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Sep 11 00:24:57.337983 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 Sep 11 00:24:57.338384 kernel: hub 1-0:1.0: USB hub found Sep 11 00:24:57.345357 kernel: hub 1-0:1.0: 2 ports detected Sep 11 00:24:57.359128 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 11 00:24:57.389351 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:24:57.418942 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:24:57.434947 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Sep 11 00:24:57.439653 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 11 00:24:57.455943 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 11 00:24:57.456642 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 11 00:24:57.458747 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 11 00:24:57.463289 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:24:57.464014 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:24:57.465637 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:24:57.469062 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 11 00:24:57.472057 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 11 00:24:57.489348 disk-uuid[618]: Primary Header is updated. Sep 11 00:24:57.489348 disk-uuid[618]: Secondary Entries is updated. Sep 11 00:24:57.489348 disk-uuid[618]: Secondary Header is updated. Sep 11 00:24:57.496946 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:24:57.506105 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:24:58.509984 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:24:58.510071 disk-uuid[620]: The operation has completed successfully. Sep 11 00:24:58.569310 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 11 00:24:58.569525 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 11 00:24:58.598094 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 11 00:24:58.628251 sh[637]: Success Sep 11 00:24:58.654117 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 11 00:24:58.654238 kernel: device-mapper: uevent: version 1.0.3 Sep 11 00:24:58.655248 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 11 00:24:58.668907 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 11 00:24:58.735468 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 11 00:24:58.741031 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 11 00:24:58.753820 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 11 00:24:58.772941 kernel: BTRFS: device fsid f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 devid 1 transid 39 /dev/mapper/usr (253:0) scanned by mount (650) Sep 11 00:24:58.775988 kernel: BTRFS info (device dm-0): first mount of filesystem f1eb5eb7-34cc-49c0-9f2b-e603bd772d66 Sep 11 00:24:58.776103 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:24:58.786429 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 11 00:24:58.786558 kernel: BTRFS info (device dm-0): enabling free space tree Sep 11 00:24:58.788727 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 11 00:24:58.790106 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:24:58.791143 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 11 00:24:58.792235 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 11 00:24:58.796071 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 11 00:24:58.842578 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (683) Sep 11 00:24:58.842681 kernel: BTRFS info (device vda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:24:58.843977 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:24:58.851992 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:24:58.852104 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:24:58.863168 kernel: BTRFS info (device vda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:24:58.864536 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 11 00:24:58.868385 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 11 00:24:58.967514 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:24:58.975055 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:24:59.039081 systemd-networkd[819]: lo: Link UP Sep 11 00:24:59.040172 systemd-networkd[819]: lo: Gained carrier Sep 11 00:24:59.044831 systemd-networkd[819]: Enumeration completed Sep 11 00:24:59.046417 systemd-networkd[819]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Sep 11 00:24:59.046423 systemd-networkd[819]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. Sep 11 00:24:59.048603 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:24:59.052654 systemd-networkd[819]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:24:59.052661 systemd-networkd[819]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:24:59.053694 systemd-networkd[819]: eth0: Link UP Sep 11 00:24:59.058876 systemd-networkd[819]: eth1: Link UP Sep 11 00:24:59.059284 systemd-networkd[819]: eth0: Gained carrier Sep 11 00:24:59.059306 systemd-networkd[819]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Sep 11 00:24:59.061755 systemd[1]: Reached target network.target - Network. Sep 11 00:24:59.067321 systemd-networkd[819]: eth1: Gained carrier Sep 11 00:24:59.067345 systemd-networkd[819]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:24:59.079031 systemd-networkd[819]: eth0: DHCPv4 address 144.126.208.231/20, gateway 144.126.208.1 acquired from 169.254.169.253 Sep 11 00:24:59.096038 systemd-networkd[819]: eth1: DHCPv4 address 10.124.0.34/20 acquired from 169.254.169.253 Sep 11 00:24:59.140417 ignition[726]: Ignition 2.21.0 Sep 11 00:24:59.140441 ignition[726]: Stage: fetch-offline Sep 11 00:24:59.140493 ignition[726]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:24:59.140505 ignition[726]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 11 00:24:59.144424 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:24:59.140649 ignition[726]: parsed url from cmdline: "" Sep 11 00:24:59.140655 ignition[726]: no config URL provided Sep 11 00:24:59.140663 ignition[726]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:24:59.140675 ignition[726]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:24:59.140683 ignition[726]: failed to fetch config: resource requires networking Sep 11 00:24:59.148156 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 11 00:24:59.141003 ignition[726]: Ignition finished successfully Sep 11 00:24:59.200788 ignition[829]: Ignition 2.21.0 Sep 11 00:24:59.200814 ignition[829]: Stage: fetch Sep 11 00:24:59.201216 ignition[829]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:24:59.201234 ignition[829]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 11 00:24:59.201510 ignition[829]: parsed url from cmdline: "" Sep 11 00:24:59.201519 ignition[829]: no config URL provided Sep 11 00:24:59.201528 ignition[829]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:24:59.201551 ignition[829]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:24:59.201620 ignition[829]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 Sep 11 00:24:59.219504 ignition[829]: GET result: OK Sep 11 00:24:59.219710 ignition[829]: parsing config with SHA512: 7e38ecc60663c0984036a733576e89dfc237775f0537f59fff467322a82760c482c40468cc1e2af84a229c111842d4bc5cc974e0bcf543bf65b497b9c284c9b4 Sep 11 00:24:59.233184 unknown[829]: fetched base config from "system" Sep 11 00:24:59.233201 unknown[829]: fetched base config from "system" Sep 11 00:24:59.233523 ignition[829]: fetch: fetch complete Sep 11 00:24:59.233208 unknown[829]: fetched user config from "digitalocean" Sep 11 00:24:59.233534 ignition[829]: fetch: fetch passed Sep 11 00:24:59.236680 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 11 00:24:59.233619 ignition[829]: Ignition finished successfully Sep 11 00:24:59.240649 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 11 00:24:59.283927 ignition[835]: Ignition 2.21.0 Sep 11 00:24:59.283948 ignition[835]: Stage: kargs Sep 11 00:24:59.284326 ignition[835]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:24:59.284344 ignition[835]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 11 00:24:59.287719 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 11 00:24:59.285913 ignition[835]: kargs: kargs passed Sep 11 00:24:59.286019 ignition[835]: Ignition finished successfully Sep 11 00:24:59.291584 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 11 00:24:59.333916 ignition[841]: Ignition 2.21.0 Sep 11 00:24:59.333930 ignition[841]: Stage: disks Sep 11 00:24:59.334235 ignition[841]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:24:59.334254 ignition[841]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 11 00:24:59.336301 ignition[841]: disks: disks passed Sep 11 00:24:59.338006 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 11 00:24:59.336468 ignition[841]: Ignition finished successfully Sep 11 00:24:59.339730 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 11 00:24:59.341211 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 11 00:24:59.342468 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:24:59.344227 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:24:59.345976 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:24:59.350201 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 11 00:24:59.385417 systemd-fsck[850]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 11 00:24:59.389400 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 11 00:24:59.394002 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 11 00:24:59.563935 kernel: EXT4-fs (vda9): mounted filesystem 6a9ce0af-81d0-4628-9791-e47488ed2744 r/w with ordered data mode. Quota mode: none. Sep 11 00:24:59.564428 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 11 00:24:59.565806 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 11 00:24:59.569019 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:24:59.572209 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 11 00:24:59.593016 systemd[1]: Starting flatcar-afterburn-network.service - Flatcar Afterburn network service... Sep 11 00:24:59.599121 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 11 00:24:59.604467 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (858) Sep 11 00:24:59.604576 kernel: BTRFS info (device vda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:24:59.606926 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:24:59.608635 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 11 00:24:59.609409 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:24:59.614094 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 11 00:24:59.617796 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 11 00:24:59.624288 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:24:59.624324 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:24:59.630844 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:24:59.734103 initrd-setup-root[888]: cut: /sysroot/etc/passwd: No such file or directory Sep 11 00:24:59.744314 initrd-setup-root[895]: cut: /sysroot/etc/group: No such file or directory Sep 11 00:24:59.747085 coreos-metadata[861]: Sep 11 00:24:59.746 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 11 00:24:59.750572 coreos-metadata[860]: Sep 11 00:24:59.750 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 11 00:24:59.756355 initrd-setup-root[902]: cut: /sysroot/etc/shadow: No such file or directory Sep 11 00:24:59.760328 coreos-metadata[861]: Sep 11 00:24:59.760 INFO Fetch successful Sep 11 00:24:59.765910 coreos-metadata[860]: Sep 11 00:24:59.764 INFO Fetch successful Sep 11 00:24:59.766823 coreos-metadata[861]: Sep 11 00:24:59.765 INFO wrote hostname ci-4372.1.0-n-33704dafec to /sysroot/etc/hostname Sep 11 00:24:59.770206 initrd-setup-root[910]: cut: /sysroot/etc/gshadow: No such file or directory Sep 11 00:24:59.767485 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 11 00:24:59.774609 systemd[1]: flatcar-afterburn-network.service: Deactivated successfully. Sep 11 00:24:59.774783 systemd[1]: Finished flatcar-afterburn-network.service - Flatcar Afterburn network service. Sep 11 00:24:59.907912 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 11 00:24:59.911129 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 11 00:24:59.914118 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 11 00:24:59.934883 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 11 00:24:59.936288 kernel: BTRFS info (device vda6): last unmount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:24:59.961013 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 11 00:24:59.980602 ignition[980]: INFO : Ignition 2.21.0 Sep 11 00:24:59.980602 ignition[980]: INFO : Stage: mount Sep 11 00:24:59.983395 ignition[980]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:24:59.983395 ignition[980]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 11 00:24:59.983395 ignition[980]: INFO : mount: mount passed Sep 11 00:24:59.983395 ignition[980]: INFO : Ignition finished successfully Sep 11 00:24:59.984287 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 11 00:24:59.988050 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 11 00:25:00.014585 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:25:00.040950 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (990) Sep 11 00:25:00.043914 kernel: BTRFS info (device vda6): first mount of filesystem a5de7b5e-e14d-4c62-883d-af7ea22fae7e Sep 11 00:25:00.044011 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:25:00.051277 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:25:00.051402 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:25:00.054625 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:25:00.095998 ignition[1007]: INFO : Ignition 2.21.0 Sep 11 00:25:00.095998 ignition[1007]: INFO : Stage: files Sep 11 00:25:00.097752 ignition[1007]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:00.097752 ignition[1007]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 11 00:25:00.097752 ignition[1007]: DEBUG : files: compiled without relabeling support, skipping Sep 11 00:25:00.100477 ignition[1007]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 11 00:25:00.100477 ignition[1007]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 11 00:25:00.102610 ignition[1007]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 11 00:25:00.103639 ignition[1007]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 11 00:25:00.103639 ignition[1007]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 11 00:25:00.103271 unknown[1007]: wrote ssh authorized keys file for user: core Sep 11 00:25:00.106315 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 11 00:25:00.106315 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 11 00:25:00.109550 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:25:00.110801 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:25:00.110801 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 11 00:25:00.113680 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 11 00:25:00.113680 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 11 00:25:00.113680 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-x86-64.raw: attempt #1 Sep 11 00:25:00.300250 systemd-networkd[819]: eth1: Gained IPv6LL Sep 11 00:25:00.475457 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 11 00:25:00.876335 systemd-networkd[819]: eth0: Gained IPv6LL Sep 11 00:25:00.958938 ignition[1007]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 11 00:25:00.961412 ignition[1007]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:25:00.963479 ignition[1007]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:25:00.963479 ignition[1007]: INFO : files: files passed Sep 11 00:25:00.963479 ignition[1007]: INFO : Ignition finished successfully Sep 11 00:25:00.965175 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 11 00:25:00.969142 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 11 00:25:00.974129 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 11 00:25:00.997568 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 11 00:25:00.997795 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 11 00:25:01.056604 initrd-setup-root-after-ignition[1037]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:01.056604 initrd-setup-root-after-ignition[1037]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:01.069525 initrd-setup-root-after-ignition[1041]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:25:01.074886 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:25:01.078315 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 11 00:25:01.083369 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 11 00:25:01.194829 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 11 00:25:01.195037 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 11 00:25:01.197506 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 11 00:25:01.198288 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 11 00:25:01.203798 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 11 00:25:01.207141 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 11 00:25:01.257583 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:25:01.265752 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 11 00:25:01.308458 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:01.311963 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:01.314040 systemd[1]: Stopped target timers.target - Timer Units. Sep 11 00:25:01.315130 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 11 00:25:01.315435 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:25:01.318336 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 11 00:25:01.324566 systemd[1]: Stopped target basic.target - Basic System. Sep 11 00:25:01.325943 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 11 00:25:01.327398 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:25:01.330824 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 11 00:25:01.331698 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:25:01.334807 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 11 00:25:01.335668 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:25:01.337341 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 11 00:25:01.339348 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 11 00:25:01.341013 systemd[1]: Stopped target swap.target - Swaps. Sep 11 00:25:01.343646 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 11 00:25:01.343994 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:25:01.347109 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:01.348501 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:01.349696 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 11 00:25:01.351581 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:01.354522 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 11 00:25:01.354797 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 11 00:25:01.357087 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 11 00:25:01.357437 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:25:01.361784 systemd[1]: ignition-files.service: Deactivated successfully. Sep 11 00:25:01.362117 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 11 00:25:01.368009 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 11 00:25:01.368323 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 11 00:25:01.378202 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 11 00:25:01.379072 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 11 00:25:01.379415 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:01.390209 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 11 00:25:01.394110 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 11 00:25:01.395384 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:01.397538 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 11 00:25:01.397754 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:25:01.407295 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 11 00:25:01.408684 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 11 00:25:01.451913 ignition[1061]: INFO : Ignition 2.21.0 Sep 11 00:25:01.451913 ignition[1061]: INFO : Stage: umount Sep 11 00:25:01.451913 ignition[1061]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:25:01.451913 ignition[1061]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 11 00:25:01.501745 ignition[1061]: INFO : umount: umount passed Sep 11 00:25:01.501745 ignition[1061]: INFO : Ignition finished successfully Sep 11 00:25:01.459411 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 11 00:25:01.475967 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 11 00:25:01.476121 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 11 00:25:01.499686 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 11 00:25:01.499889 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 11 00:25:01.500660 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 11 00:25:01.500744 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 11 00:25:01.502599 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 11 00:25:01.502701 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 11 00:25:01.503808 systemd[1]: Stopped target network.target - Network. Sep 11 00:25:01.504424 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 11 00:25:01.504536 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:25:01.505553 systemd[1]: Stopped target paths.target - Path Units. Sep 11 00:25:01.506172 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 11 00:25:01.510012 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:01.510730 systemd[1]: Stopped target slices.target - Slice Units. Sep 11 00:25:01.511202 systemd[1]: Stopped target sockets.target - Socket Units. Sep 11 00:25:01.512055 systemd[1]: iscsid.socket: Deactivated successfully. Sep 11 00:25:01.512134 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:25:01.513791 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 11 00:25:01.513889 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:25:01.514886 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 11 00:25:01.515004 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 11 00:25:01.526822 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 11 00:25:01.527086 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 11 00:25:01.540428 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 11 00:25:01.541273 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 11 00:25:01.570341 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 11 00:25:01.570529 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 11 00:25:01.579263 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 11 00:25:01.579785 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 11 00:25:01.580141 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 11 00:25:01.582846 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 11 00:25:01.583267 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 11 00:25:01.583426 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 11 00:25:01.587537 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 11 00:25:01.589204 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 11 00:25:01.589268 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:01.591507 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 11 00:25:01.591617 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 11 00:25:01.595078 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 11 00:25:01.599610 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 11 00:25:01.599911 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:25:01.602183 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 11 00:25:01.602299 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:01.605087 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 11 00:25:01.605177 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:01.607576 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 11 00:25:01.607709 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:01.609818 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:01.614762 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 11 00:25:01.614931 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:01.640500 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 11 00:25:01.641114 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:01.643683 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 11 00:25:01.643827 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 11 00:25:01.646492 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 11 00:25:01.646627 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:01.648505 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 11 00:25:01.648564 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:01.651321 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 11 00:25:01.651432 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:25:01.652276 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 11 00:25:01.652344 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 11 00:25:01.653082 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 11 00:25:01.653158 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:25:01.655672 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 11 00:25:01.657231 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 11 00:25:01.657344 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:01.665210 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 11 00:25:01.665311 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:01.668102 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:01.668200 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:01.677405 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 11 00:25:01.677543 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 11 00:25:01.677605 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:01.684589 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 11 00:25:01.684766 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 11 00:25:01.688897 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 11 00:25:01.692124 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 11 00:25:01.719732 systemd[1]: Switching root. Sep 11 00:25:01.817997 systemd-journald[212]: Received SIGTERM from PID 1 (systemd). Sep 11 00:25:01.818145 systemd-journald[212]: Journal stopped Sep 11 00:25:03.884382 kernel: SELinux: policy capability network_peer_controls=1 Sep 11 00:25:03.884666 kernel: SELinux: policy capability open_perms=1 Sep 11 00:25:03.884702 kernel: SELinux: policy capability extended_socket_class=1 Sep 11 00:25:03.886943 kernel: SELinux: policy capability always_check_network=0 Sep 11 00:25:03.886986 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 11 00:25:03.887006 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 11 00:25:03.887036 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 11 00:25:03.887062 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 11 00:25:03.887082 kernel: SELinux: policy capability userspace_initial_context=0 Sep 11 00:25:03.887102 kernel: audit: type=1403 audit(1757550302.025:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 11 00:25:03.887138 systemd[1]: Successfully loaded SELinux policy in 64.745ms. Sep 11 00:25:03.887202 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 16.834ms. Sep 11 00:25:03.887224 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 11 00:25:03.887246 systemd[1]: Detected virtualization kvm. Sep 11 00:25:03.887266 systemd[1]: Detected architecture x86-64. Sep 11 00:25:03.887293 systemd[1]: Detected first boot. Sep 11 00:25:03.887312 systemd[1]: Hostname set to . Sep 11 00:25:03.887339 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:25:03.887362 zram_generator::config[1105]: No configuration found. Sep 11 00:25:03.887396 kernel: Guest personality initialized and is inactive Sep 11 00:25:03.887415 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 11 00:25:03.887433 kernel: Initialized host personality Sep 11 00:25:03.887452 kernel: NET: Registered PF_VSOCK protocol family Sep 11 00:25:03.887470 systemd[1]: Populated /etc with preset unit settings. Sep 11 00:25:03.887492 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 11 00:25:03.887511 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 11 00:25:03.887532 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 11 00:25:03.887556 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 11 00:25:03.887579 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 11 00:25:03.887598 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 11 00:25:03.887617 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 11 00:25:03.887636 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 11 00:25:03.887656 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 11 00:25:03.887692 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 11 00:25:03.887723 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 11 00:25:03.887758 systemd[1]: Created slice user.slice - User and Session Slice. Sep 11 00:25:03.887787 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:25:03.887808 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:25:03.887831 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 11 00:25:03.887853 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 11 00:25:03.889127 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 11 00:25:03.889176 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:25:03.889202 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 11 00:25:03.890229 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:25:03.890279 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:25:03.890300 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 11 00:25:03.890321 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 11 00:25:03.890340 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 11 00:25:03.890366 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 11 00:25:03.890386 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:25:03.890406 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:25:03.890656 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:25:03.890689 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:25:03.890709 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 11 00:25:03.890729 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 11 00:25:03.890749 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 11 00:25:03.890769 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:25:03.890789 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:25:03.890999 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:25:03.891022 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 11 00:25:03.891044 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 11 00:25:03.891070 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 11 00:25:03.891221 systemd[1]: Mounting media.mount - External Media Directory... Sep 11 00:25:03.891255 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:03.891284 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 11 00:25:03.891305 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 11 00:25:03.891332 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 11 00:25:03.891481 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 11 00:25:03.891507 systemd[1]: Reached target machines.target - Containers. Sep 11 00:25:03.891538 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 11 00:25:03.891559 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:03.891580 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:25:03.891599 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 11 00:25:03.891795 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:25:03.891822 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:25:03.891847 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:25:03.891901 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 11 00:25:03.892039 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:25:03.892074 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 11 00:25:03.892095 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 11 00:25:03.892124 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 11 00:25:03.892304 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 11 00:25:03.892334 systemd[1]: Stopped systemd-fsck-usr.service. Sep 11 00:25:03.892354 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:25:03.892372 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:25:03.892529 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:25:03.892557 kernel: loop: module loaded Sep 11 00:25:03.892581 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:25:03.892607 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 11 00:25:03.892629 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 11 00:25:03.892790 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:25:03.892822 systemd[1]: verity-setup.service: Deactivated successfully. Sep 11 00:25:03.892842 systemd[1]: Stopped verity-setup.service. Sep 11 00:25:03.893006 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:03.893033 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 11 00:25:03.893053 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 11 00:25:03.893085 systemd[1]: Mounted media.mount - External Media Directory. Sep 11 00:25:03.893108 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 11 00:25:03.893126 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 11 00:25:03.893145 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 11 00:25:03.893165 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:25:03.893184 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 11 00:25:03.893337 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 11 00:25:03.893368 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:25:03.893388 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:25:03.893419 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:25:03.893571 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:25:03.893599 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:25:03.893619 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:25:03.893641 kernel: ACPI: bus type drm_connector registered Sep 11 00:25:03.893666 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 11 00:25:03.893804 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:25:03.894004 kernel: fuse: init (API version 7.41) Sep 11 00:25:03.894028 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:25:03.894060 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:25:03.894086 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 11 00:25:03.894238 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 11 00:25:03.894263 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 11 00:25:03.894294 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 11 00:25:03.894315 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 11 00:25:03.894529 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:25:03.894557 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 11 00:25:03.894578 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 11 00:25:03.894602 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:03.894622 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 11 00:25:03.895131 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:25:03.895960 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 11 00:25:03.895989 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 11 00:25:03.896010 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:25:03.896037 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:25:03.896058 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:25:03.896084 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:25:03.896114 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 11 00:25:03.896233 systemd-journald[1179]: Collecting audit messages is disabled. Sep 11 00:25:03.896288 kernel: loop0: detected capacity change from 0 to 113872 Sep 11 00:25:03.896310 systemd-journald[1179]: Journal started Sep 11 00:25:03.896354 systemd-journald[1179]: Runtime Journal (/run/log/journal/a8e8cbc7caa64a5f8bfc2379642fa437) is 4.9M, max 39.6M, 34.6M free. Sep 11 00:25:02.954052 systemd[1]: Queued start job for default target multi-user.target. Sep 11 00:25:03.903993 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:25:02.963619 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 11 00:25:02.964392 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 11 00:25:03.901422 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 11 00:25:03.902950 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 11 00:25:03.907695 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 11 00:25:03.916259 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 11 00:25:03.949261 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 11 00:25:03.985610 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 11 00:25:04.009436 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 11 00:25:04.017207 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 11 00:25:04.021812 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:25:04.032685 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 11 00:25:04.039591 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 11 00:25:04.040966 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 11 00:25:04.046611 kernel: loop1: detected capacity change from 0 to 221472 Sep 11 00:25:04.046804 systemd-journald[1179]: Time spent on flushing to /var/log/journal/a8e8cbc7caa64a5f8bfc2379642fa437 is 52.691ms for 1004 entries. Sep 11 00:25:04.046804 systemd-journald[1179]: System Journal (/var/log/journal/a8e8cbc7caa64a5f8bfc2379642fa437) is 8M, max 195.6M, 187.6M free. Sep 11 00:25:04.117696 systemd-journald[1179]: Received client request to flush runtime journal. Sep 11 00:25:04.117789 kernel: loop2: detected capacity change from 0 to 146240 Sep 11 00:25:04.070745 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:25:04.119696 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 11 00:25:04.143893 kernel: loop3: detected capacity change from 0 to 8 Sep 11 00:25:04.161967 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 11 00:25:04.167134 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:25:04.175910 kernel: loop4: detected capacity change from 0 to 113872 Sep 11 00:25:04.201806 kernel: loop5: detected capacity change from 0 to 221472 Sep 11 00:25:04.228910 kernel: loop6: detected capacity change from 0 to 146240 Sep 11 00:25:04.266976 kernel: loop7: detected capacity change from 0 to 8 Sep 11 00:25:04.272320 (sd-merge)[1251]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. Sep 11 00:25:04.273177 (sd-merge)[1251]: Merged extensions into '/usr'. Sep 11 00:25:04.277096 systemd-tmpfiles[1250]: ACLs are not supported, ignoring. Sep 11 00:25:04.277969 systemd-tmpfiles[1250]: ACLs are not supported, ignoring. Sep 11 00:25:04.285121 systemd[1]: Reload requested from client PID 1199 ('systemd-sysext') (unit systemd-sysext.service)... Sep 11 00:25:04.285150 systemd[1]: Reloading... Sep 11 00:25:04.471901 zram_generator::config[1279]: No configuration found. Sep 11 00:25:04.744959 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 11 00:25:04.855907 ldconfig[1196]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:25:04.892831 systemd[1]: Reloading finished in 606 ms. Sep 11 00:25:04.908116 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 11 00:25:04.909968 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:25:04.912527 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 11 00:25:04.928153 systemd[1]: Starting ensure-sysext.service... Sep 11 00:25:04.933249 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:25:04.985423 systemd[1]: Reload requested from client PID 1323 ('systemctl') (unit ensure-sysext.service)... Sep 11 00:25:04.985461 systemd[1]: Reloading... Sep 11 00:25:05.031291 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 11 00:25:05.031908 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 11 00:25:05.032423 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 11 00:25:05.033244 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 11 00:25:05.038025 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 11 00:25:05.038455 systemd-tmpfiles[1324]: ACLs are not supported, ignoring. Sep 11 00:25:05.038537 systemd-tmpfiles[1324]: ACLs are not supported, ignoring. Sep 11 00:25:05.044428 systemd-tmpfiles[1324]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:25:05.046120 systemd-tmpfiles[1324]: Skipping /boot Sep 11 00:25:05.105527 systemd-tmpfiles[1324]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:25:05.106916 systemd-tmpfiles[1324]: Skipping /boot Sep 11 00:25:05.156906 zram_generator::config[1360]: No configuration found. Sep 11 00:25:05.288037 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 11 00:25:05.402977 systemd[1]: Reloading finished in 416 ms. Sep 11 00:25:05.425022 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 11 00:25:05.438001 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:25:05.448739 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:25:05.453240 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 11 00:25:05.457959 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 11 00:25:05.464064 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:25:05.470231 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:25:05.476035 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 11 00:25:05.483001 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.483307 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:05.493937 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:25:05.497227 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:25:05.503317 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:25:05.506130 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:05.506359 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:25:05.506519 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.514636 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.517035 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:05.517368 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:05.517640 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:25:05.524517 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 11 00:25:05.525372 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.539440 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.539740 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:05.543222 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:25:05.546092 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:05.546330 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:25:05.546545 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.550190 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 11 00:25:05.558575 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 11 00:25:05.559857 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:25:05.560755 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:25:05.563506 systemd[1]: Finished ensure-sysext.service. Sep 11 00:25:05.565358 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:25:05.565602 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:25:05.567259 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:25:05.567490 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:25:05.574687 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:25:05.574963 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:25:05.583032 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:25:05.583138 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:25:05.593098 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 11 00:25:05.593721 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 11 00:25:05.609384 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 11 00:25:05.613743 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 11 00:25:05.619588 systemd-udevd[1400]: Using default interface naming scheme 'v255'. Sep 11 00:25:05.643622 augenrules[1437]: No rules Sep 11 00:25:05.645633 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:25:05.646454 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:25:05.655789 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 11 00:25:05.664372 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:25:05.671174 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:25:05.671962 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 11 00:25:05.860128 systemd[1]: Condition check resulted in dev-disk-by\x2dlabel-config\x2d2.device - /dev/disk/by-label/config-2 being skipped. Sep 11 00:25:05.863233 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... Sep 11 00:25:05.864068 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.864336 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:25:05.867119 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:25:05.871136 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:25:05.878242 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:25:05.879186 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:25:05.879226 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:25:05.879262 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 11 00:25:05.879287 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:25:05.936837 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:25:05.937194 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:25:05.942954 kernel: ISO 9660 Extensions: RRIP_1991A Sep 11 00:25:05.945544 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. Sep 11 00:25:05.979712 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:25:05.982071 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:25:05.983089 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:25:05.984032 systemd-networkd[1449]: lo: Link UP Sep 11 00:25:05.984039 systemd-networkd[1449]: lo: Gained carrier Sep 11 00:25:05.985174 systemd-networkd[1449]: Enumeration completed Sep 11 00:25:05.985293 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:25:05.988080 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 11 00:25:05.991042 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 11 00:25:06.001696 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:25:06.002948 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:25:06.006635 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:25:06.017055 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 11 00:25:06.034372 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 11 00:25:06.057659 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 11 00:25:06.063928 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:25:06.065036 systemd[1]: Reached target time-set.target - System Time Set. Sep 11 00:25:06.070141 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 11 00:25:06.077729 systemd-resolved[1399]: Positive Trust Anchors: Sep 11 00:25:06.077746 systemd-resolved[1399]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:25:06.077783 systemd-resolved[1399]: 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 11 00:25:06.085128 systemd-resolved[1399]: Using system hostname 'ci-4372.1.0-n-33704dafec'. Sep 11 00:25:06.087993 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:25:06.089147 systemd[1]: Reached target network.target - Network. Sep 11 00:25:06.090300 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:25:06.091554 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:25:06.093058 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 11 00:25:06.093827 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 11 00:25:06.094692 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 11 00:25:06.096189 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 11 00:25:06.097134 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 11 00:25:06.098524 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 11 00:25:06.099140 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 11 00:25:06.099179 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:25:06.099659 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:25:06.102338 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 11 00:25:06.107120 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 11 00:25:06.114097 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 11 00:25:06.115821 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 11 00:25:06.117696 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 11 00:25:06.129405 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 11 00:25:06.130621 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 11 00:25:06.134133 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 11 00:25:06.134967 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 11 00:25:06.138435 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:25:06.139988 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:25:06.141651 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:25:06.142983 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:25:06.146182 systemd[1]: Starting containerd.service - containerd container runtime... Sep 11 00:25:06.152191 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 11 00:25:06.158188 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 11 00:25:06.163819 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 11 00:25:06.174045 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 11 00:25:06.180313 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 11 00:25:06.180990 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 11 00:25:06.185191 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 11 00:25:06.189765 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 11 00:25:06.192812 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 11 00:25:06.198281 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 11 00:25:06.211393 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 11 00:25:06.214500 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 11 00:25:06.215203 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 11 00:25:06.219976 systemd[1]: Starting update-engine.service - Update Engine... Sep 11 00:25:06.241911 coreos-metadata[1506]: Sep 11 00:25:06.232 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 11 00:25:06.239161 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 11 00:25:06.242328 extend-filesystems[1511]: Found /dev/vda6 Sep 11 00:25:06.242328 extend-filesystems[1511]: Found /dev/vda9 Sep 11 00:25:06.260318 extend-filesystems[1511]: Checking size of /dev/vda9 Sep 11 00:25:06.251956 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 11 00:25:06.275230 coreos-metadata[1506]: Sep 11 00:25:06.269 INFO Failed to fetch: error sending request for url (http://169.254.169.254/metadata/v1.json) Sep 11 00:25:06.279830 systemd-networkd[1449]: eth1: Configuring with /run/systemd/network/10-52:2f:79:b9:f7:03.network. Sep 11 00:25:06.297358 jq[1510]: false Sep 11 00:25:06.280777 systemd-networkd[1449]: eth1: Link UP Sep 11 00:25:06.282079 systemd-networkd[1449]: eth1: Gained carrier Sep 11 00:25:06.288238 systemd-timesyncd[1430]: Network configuration changed, trying to establish connection. Sep 11 00:25:06.289068 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 11 00:25:06.290412 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 11 00:25:06.295397 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 11 00:25:06.295695 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 11 00:25:06.301340 dbus-daemon[1507]: [system] SELinux support is enabled Sep 11 00:25:06.301624 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 11 00:25:06.309583 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 11 00:25:06.310722 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 11 00:25:06.311439 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 11 00:25:06.311540 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean). Sep 11 00:25:06.311557 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 11 00:25:06.327100 google_oslogin_nss_cache[1513]: oslogin_cache_refresh[1513]: Refreshing passwd entry cache Sep 11 00:25:06.327100 google_oslogin_nss_cache[1513]: oslogin_cache_refresh[1513]: Failure getting users, quitting Sep 11 00:25:06.327100 google_oslogin_nss_cache[1513]: oslogin_cache_refresh[1513]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:25:06.327100 google_oslogin_nss_cache[1513]: oslogin_cache_refresh[1513]: Refreshing group entry cache Sep 11 00:25:06.323539 oslogin_cache_refresh[1513]: Refreshing passwd entry cache Sep 11 00:25:06.327688 google_oslogin_nss_cache[1513]: oslogin_cache_refresh[1513]: Failure getting groups, quitting Sep 11 00:25:06.327688 google_oslogin_nss_cache[1513]: oslogin_cache_refresh[1513]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:25:06.326354 oslogin_cache_refresh[1513]: Failure getting users, quitting Sep 11 00:25:06.326376 oslogin_cache_refresh[1513]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:25:06.332637 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 11 00:25:06.326443 oslogin_cache_refresh[1513]: Refreshing group entry cache Sep 11 00:25:06.336120 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 11 00:25:06.327185 oslogin_cache_refresh[1513]: Failure getting groups, quitting Sep 11 00:25:06.343592 (ntainerd)[1531]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 11 00:25:06.327198 oslogin_cache_refresh[1513]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:25:06.361924 jq[1523]: true Sep 11 00:25:06.364235 extend-filesystems[1511]: Resized partition /dev/vda9 Sep 11 00:25:06.367716 systemd-networkd[1449]: eth0: Configuring with /run/systemd/network/10-92:10:ac:56:d4:72.network. Sep 11 00:25:06.383476 extend-filesystems[1545]: resize2fs 1.47.2 (1-Jan-2025) Sep 11 00:25:06.378562 systemd-networkd[1449]: eth0: Link UP Sep 11 00:25:06.379687 systemd-networkd[1449]: eth0: Gained carrier Sep 11 00:25:06.393012 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks Sep 11 00:25:06.409945 update_engine[1519]: I20250911 00:25:06.409392 1519 main.cc:92] Flatcar Update Engine starting Sep 11 00:25:06.431651 update_engine[1519]: I20250911 00:25:06.431171 1519 update_check_scheduler.cc:74] Next update check in 9m15s Sep 11 00:25:06.431806 systemd[1]: Started update-engine.service - Update Engine. Sep 11 00:25:06.440955 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 11 00:25:06.461740 jq[1544]: true Sep 11 00:25:06.455737 systemd[1]: motdgen.service: Deactivated successfully. Sep 11 00:25:06.456091 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 11 00:25:06.548058 kernel: mousedev: PS/2 mouse device common for all mice Sep 11 00:25:06.567365 systemd-logind[1518]: New seat seat0. Sep 11 00:25:06.571975 systemd[1]: Started systemd-logind.service - User Login Management. Sep 11 00:25:06.581895 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 11 00:25:06.587389 kernel: ACPI: button: Power Button [PWRF] Sep 11 00:25:06.659045 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Sep 11 00:25:06.659143 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Sep 11 00:25:06.659767 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 11 00:25:06.638072 locksmithd[1551]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 11 00:25:06.661718 extend-filesystems[1545]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 11 00:25:06.661718 extend-filesystems[1545]: old_desc_blocks = 1, new_desc_blocks = 8 Sep 11 00:25:06.661718 extend-filesystems[1545]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Sep 11 00:25:06.671170 extend-filesystems[1511]: Resized filesystem in /dev/vda9 Sep 11 00:25:06.666774 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 11 00:25:06.678370 bash[1572]: Updated "/home/core/.ssh/authorized_keys" Sep 11 00:25:06.667128 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 11 00:25:06.677486 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 11 00:25:06.685510 systemd[1]: Starting sshkeys.service... Sep 11 00:25:06.746439 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Sep 11 00:25:06.748927 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Sep 11 00:25:06.870357 coreos-metadata[1581]: Sep 11 00:25:06.870 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 11 00:25:06.872658 containerd[1531]: time="2025-09-11T00:25:06Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 11 00:25:06.874008 containerd[1531]: time="2025-09-11T00:25:06.873956474Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 11 00:25:06.889905 coreos-metadata[1581]: Sep 11 00:25:06.885 INFO Fetch successful Sep 11 00:25:06.910040 unknown[1581]: wrote ssh authorized keys file for user: core Sep 11 00:25:06.919375 containerd[1531]: time="2025-09-11T00:25:06.919279071Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="11.264µs" Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.923922321Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.923989212Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924177780Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924192651Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924218685Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924302723Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924316375Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924598863Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924622576Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924635777Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924644325Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 11 00:25:06.924907 containerd[1531]: time="2025-09-11T00:25:06.924805982Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 11 00:25:06.926245 containerd[1531]: time="2025-09-11T00:25:06.926204611Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:25:06.929816 containerd[1531]: time="2025-09-11T00:25:06.929170916Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:25:06.932796 containerd[1531]: time="2025-09-11T00:25:06.932623913Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 11 00:25:06.932796 containerd[1531]: time="2025-09-11T00:25:06.932734944Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 11 00:25:06.947249 containerd[1531]: time="2025-09-11T00:25:06.947193372Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 11 00:25:06.950654 containerd[1531]: time="2025-09-11T00:25:06.950014377Z" level=info msg="metadata content store policy set" policy=shared Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963022990Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963165712Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963184455Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963198425Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963227739Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963239991Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963251661Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 11 00:25:06.963306 containerd[1531]: time="2025-09-11T00:25:06.963277750Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 11 00:25:06.963900 containerd[1531]: time="2025-09-11T00:25:06.963750643Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 11 00:25:06.963900 containerd[1531]: time="2025-09-11T00:25:06.963775000Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 11 00:25:06.963900 containerd[1531]: time="2025-09-11T00:25:06.963785354Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 11 00:25:06.963900 containerd[1531]: time="2025-09-11T00:25:06.963816488Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 11 00:25:06.966609 containerd[1531]: time="2025-09-11T00:25:06.965695124Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 11 00:25:06.966609 containerd[1531]: time="2025-09-11T00:25:06.965765902Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 11 00:25:06.966609 containerd[1531]: time="2025-09-11T00:25:06.965786633Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 11 00:25:06.966609 containerd[1531]: time="2025-09-11T00:25:06.965799131Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 11 00:25:06.966609 containerd[1531]: time="2025-09-11T00:25:06.965823726Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 11 00:25:06.966609 containerd[1531]: time="2025-09-11T00:25:06.965837267Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 11 00:25:06.967140 update-ssh-keys[1597]: Updated "/home/core/.ssh/authorized_keys" Sep 11 00:25:06.967774 containerd[1531]: time="2025-09-11T00:25:06.965859497Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 11 00:25:06.967774 containerd[1531]: time="2025-09-11T00:25:06.967621971Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 11 00:25:06.967774 containerd[1531]: time="2025-09-11T00:25:06.967655209Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 11 00:25:06.967774 containerd[1531]: time="2025-09-11T00:25:06.967666956Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 11 00:25:06.967774 containerd[1531]: time="2025-09-11T00:25:06.967695129Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 11 00:25:06.968049 containerd[1531]: time="2025-09-11T00:25:06.967947158Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 11 00:25:06.971272 containerd[1531]: time="2025-09-11T00:25:06.968944315Z" level=info msg="Start snapshots syncer" Sep 11 00:25:06.971272 containerd[1531]: time="2025-09-11T00:25:06.968993366Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 11 00:25:06.969361 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Sep 11 00:25:06.971759 containerd[1531]: time="2025-09-11T00:25:06.970503287Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 11 00:25:06.973937 containerd[1531]: time="2025-09-11T00:25:06.972975334Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 11 00:25:06.973200 systemd[1]: Finished sshkeys.service. Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974099648Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974279271Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974304007Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974315331Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974328258Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974340721Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974351673Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974363131Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974390549Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974401332Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 11 00:25:06.974454 containerd[1531]: time="2025-09-11T00:25:06.974412521Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 11 00:25:06.974883 containerd[1531]: time="2025-09-11T00:25:06.974794325Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:25:06.974883 containerd[1531]: time="2025-09-11T00:25:06.974821157Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:25:06.974883 containerd[1531]: time="2025-09-11T00:25:06.974831298Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:25:06.974883 containerd[1531]: time="2025-09-11T00:25:06.974840363Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:25:06.974883 containerd[1531]: time="2025-09-11T00:25:06.974848463Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 11 00:25:06.974883 containerd[1531]: time="2025-09-11T00:25:06.974857274Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 11 00:25:06.975942 containerd[1531]: time="2025-09-11T00:25:06.975017008Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 11 00:25:06.975942 containerd[1531]: time="2025-09-11T00:25:06.975038639Z" level=info msg="runtime interface created" Sep 11 00:25:06.975942 containerd[1531]: time="2025-09-11T00:25:06.975044272Z" level=info msg="created NRI interface" Sep 11 00:25:06.975942 containerd[1531]: time="2025-09-11T00:25:06.975054193Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 11 00:25:06.975942 containerd[1531]: time="2025-09-11T00:25:06.975069827Z" level=info msg="Connect containerd service" Sep 11 00:25:06.975942 containerd[1531]: time="2025-09-11T00:25:06.975099495Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 11 00:25:06.975942 containerd[1531]: time="2025-09-11T00:25:06.975841773Z" 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 11 00:25:07.139164 sshd_keygen[1550]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 11 00:25:07.226563 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 11 00:25:07.235287 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 11 00:25:07.268508 systemd[1]: issuegen.service: Deactivated successfully. Sep 11 00:25:07.270307 coreos-metadata[1506]: Sep 11 00:25:07.269 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #2 Sep 11 00:25:07.269494 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 11 00:25:07.275758 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 11 00:25:07.282937 coreos-metadata[1506]: Sep 11 00:25:07.282 INFO Fetch successful Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.357922112Z" level=info msg="Start subscribing containerd event" Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358097820Z" level=info msg="Start recovering state" Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358104105Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358347655Z" level=info msg="Start event monitor" Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358373672Z" level=info msg="Start cni network conf syncer for default" Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358391398Z" level=info msg="Start streaming server" Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358355916Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358432705Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358444744Z" level=info msg="runtime interface starting up..." Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358454342Z" level=info msg="starting plugins..." Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358476639Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 11 00:25:07.359662 containerd[1531]: time="2025-09-11T00:25:07.358986794Z" level=info msg="containerd successfully booted in 0.487627s" Sep 11 00:25:07.359116 systemd[1]: Started containerd.service - containerd container runtime. Sep 11 00:25:07.362764 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 11 00:25:07.375567 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 11 00:25:07.382390 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 11 00:25:07.383642 systemd[1]: Reached target getty.target - Login Prompts. Sep 11 00:25:07.415278 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:07.418757 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 11 00:25:07.420696 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 11 00:25:07.470064 systemd-networkd[1449]: eth1: Gained IPv6LL Sep 11 00:25:07.479127 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 11 00:25:07.485317 systemd[1]: Reached target network-online.target - Network is Online. Sep 11 00:25:07.493217 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 11 00:25:07.498214 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 11 00:25:07.501830 systemd-logind[1518]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 11 00:25:07.534445 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Sep 11 00:25:07.534554 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Sep 11 00:25:07.542737 kernel: Console: switching to colour dummy device 80x25 Sep 11 00:25:07.542905 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Sep 11 00:25:07.542934 kernel: [drm] features: -context_init Sep 11 00:25:07.548415 kernel: [drm] number of scanouts: 1 Sep 11 00:25:07.548546 kernel: [drm] number of cap sets: 0 Sep 11 00:25:07.551277 kernel: [drm] Initialized virtio_gpu 0.1.0 for 0000:00:02.0 on minor 0 Sep 11 00:25:07.555245 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Sep 11 00:25:07.555355 kernel: Console: switching to colour frame buffer device 128x48 Sep 11 00:25:07.566124 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Sep 11 00:25:07.654068 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 11 00:25:07.665991 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:07.704730 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:07.705087 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:07.709725 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:07.716706 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:07.726032 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:07.738214 systemd-logind[1518]: Watching system buttons on /dev/input/event2 (Power Button) Sep 11 00:25:07.788385 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:25:07.788756 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:07.792843 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:25:07.820689 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:25:07.858129 systemd-networkd[1449]: eth0: Gained IPv6LL Sep 11 00:25:07.955758 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:25:07.973906 kernel: EDAC MC: Ver: 3.0.0 Sep 11 00:25:08.966999 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 11 00:25:08.969862 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 11 00:25:08.971285 systemd[1]: Startup finished in 4.596s (kernel) + 6.355s (initrd) + 7.007s (userspace) = 17.959s. Sep 11 00:25:08.976588 (kubelet)[1678]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 11 00:25:09.210947 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 11 00:25:09.214247 systemd[1]: Started sshd@0-144.126.208.231:22-147.75.109.163:46808.service - OpenSSH per-connection server daemon (147.75.109.163:46808). Sep 11 00:25:09.362463 sshd[1688]: Accepted publickey for core from 147.75.109.163 port 46808 ssh2: RSA SHA256:75v2InfL/m+9WH/isPfMfMWFJ5o78V3wTlaMzBZardQ Sep 11 00:25:09.366842 sshd-session[1688]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:25:09.377921 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 11 00:25:09.380154 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 11 00:25:09.398330 systemd-logind[1518]: New session 1 of user core. Sep 11 00:25:09.430659 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 11 00:25:09.435856 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 11 00:25:09.453420 (systemd)[1692]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 11 00:25:09.459854 systemd-logind[1518]: New session c1 of user core. Sep 11 00:25:09.633484 systemd[1692]: Queued start job for default target default.target. Sep 11 00:25:09.640721 systemd[1692]: Created slice app.slice - User Application Slice. Sep 11 00:25:09.640766 systemd[1692]: Reached target paths.target - Paths. Sep 11 00:25:09.641061 systemd[1692]: Reached target timers.target - Timers. Sep 11 00:25:09.643165 systemd[1692]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:25:09.672660 systemd[1692]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:25:09.673157 systemd[1692]: Reached target sockets.target - Sockets. Sep 11 00:25:09.674842 systemd[1692]: Reached target basic.target - Basic System. Sep 11 00:25:09.674941 systemd[1692]: Reached target default.target - Main User Target. Sep 11 00:25:09.674980 systemd[1692]: Startup finished in 203ms. Sep 11 00:25:09.675160 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 11 00:25:09.682152 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 11 00:25:09.740767 kubelet[1678]: E0911 00:25:09.740565 1678 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 11 00:25:09.754295 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 11 00:25:09.754918 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 11 00:25:09.756554 systemd[1]: kubelet.service: Consumed 1.550s CPU time, 263.8M memory peak. Sep 11 00:25:09.765384 systemd[1]: Started sshd@1-144.126.208.231:22-147.75.109.163:46818.service - OpenSSH per-connection server daemon (147.75.109.163:46818). Sep 11 00:25:09.845947 sshd[1705]: Accepted publickey for core from 147.75.109.163 port 46818 ssh2: RSA SHA256:75v2InfL/m+9WH/isPfMfMWFJ5o78V3wTlaMzBZardQ Sep 11 00:25:09.847839 sshd-session[1705]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:25:09.862474 systemd-logind[1518]: New session 2 of user core. Sep 11 00:25:09.874268 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 11 00:25:09.938847 sshd[1707]: Connection closed by 147.75.109.163 port 46818 Sep 11 00:25:09.939551 sshd-session[1705]: pam_unix(sshd:session): session closed for user core Sep 11 00:25:09.956438 systemd[1]: sshd@1-144.126.208.231:22-147.75.109.163:46818.service: Deactivated successfully. Sep 11 00:25:09.958474 systemd[1]: session-2.scope: Deactivated successfully. Sep 11 00:25:09.960255 systemd-logind[1518]: Session 2 logged out. Waiting for processes to exit. Sep 11 00:25:09.963186 systemd[1]: Started sshd@2-144.126.208.231:22-147.75.109.163:46822.service - OpenSSH per-connection server daemon (147.75.109.163:46822). Sep 11 00:25:09.966000 systemd-logind[1518]: Removed session 2. Sep 11 00:25:10.040734 sshd[1713]: Accepted publickey for core from 147.75.109.163 port 46822 ssh2: RSA SHA256:75v2InfL/m+9WH/isPfMfMWFJ5o78V3wTlaMzBZardQ Sep 11 00:25:10.042749 sshd-session[1713]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:25:10.050991 systemd-logind[1518]: New session 3 of user core. Sep 11 00:25:10.061281 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 11 00:25:10.120436 sshd[1715]: Connection closed by 147.75.109.163 port 46822 Sep 11 00:25:10.121336 sshd-session[1713]: pam_unix(sshd:session): session closed for user core Sep 11 00:25:10.136639 systemd[1]: sshd@2-144.126.208.231:22-147.75.109.163:46822.service: Deactivated successfully. Sep 11 00:25:10.139787 systemd[1]: session-3.scope: Deactivated successfully. Sep 11 00:25:10.142145 systemd-logind[1518]: Session 3 logged out. Waiting for processes to exit. Sep 11 00:25:10.146387 systemd[1]: Started sshd@3-144.126.208.231:22-147.75.109.163:46832.service - OpenSSH per-connection server daemon (147.75.109.163:46832). Sep 11 00:25:10.148338 systemd-logind[1518]: Removed session 3. Sep 11 00:25:10.222841 sshd[1721]: Accepted publickey for core from 147.75.109.163 port 46832 ssh2: RSA SHA256:75v2InfL/m+9WH/isPfMfMWFJ5o78V3wTlaMzBZardQ Sep 11 00:25:10.225403 sshd-session[1721]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:25:10.232828 systemd-logind[1518]: New session 4 of user core. Sep 11 00:25:10.243281 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 11 00:25:10.310013 sshd[1723]: Connection closed by 147.75.109.163 port 46832 Sep 11 00:25:10.310978 sshd-session[1721]: pam_unix(sshd:session): session closed for user core Sep 11 00:25:10.325470 systemd[1]: sshd@3-144.126.208.231:22-147.75.109.163:46832.service: Deactivated successfully. Sep 11 00:25:10.327885 systemd[1]: session-4.scope: Deactivated successfully. Sep 11 00:25:10.329127 systemd-logind[1518]: Session 4 logged out. Waiting for processes to exit. Sep 11 00:25:10.333631 systemd[1]: Started sshd@4-144.126.208.231:22-147.75.109.163:46842.service - OpenSSH per-connection server daemon (147.75.109.163:46842). Sep 11 00:25:10.334603 systemd-logind[1518]: Removed session 4. Sep 11 00:25:10.407313 sshd[1729]: Accepted publickey for core from 147.75.109.163 port 46842 ssh2: RSA SHA256:75v2InfL/m+9WH/isPfMfMWFJ5o78V3wTlaMzBZardQ Sep 11 00:25:10.410050 sshd-session[1729]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:25:10.418115 systemd-logind[1518]: New session 5 of user core. Sep 11 00:25:10.424257 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 11 00:25:10.498224 sudo[1732]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 11 00:25:10.498535 sudo[1732]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:25:11.284897 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 11 00:25:11.285169 systemd[1]: kubelet.service: Consumed 1.550s CPU time, 263.8M memory peak. Sep 11 00:25:11.288973 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 11 00:25:11.334246 systemd[1]: Reload requested from client PID 1765 ('systemctl') (unit session-5.scope)... Sep 11 00:25:11.334274 systemd[1]: Reloading... Sep 11 00:25:11.491265 zram_generator::config[1807]: No configuration found. Sep 11 00:25:11.622340 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 11 00:25:11.749146 systemd[1]: Reloading finished in 414 ms. Sep 11 00:25:11.818623 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 11 00:25:11.818777 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 11 00:25:11.819205 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 11 00:25:11.819276 systemd[1]: kubelet.service: Consumed 138ms CPU time, 98.3M memory peak. Sep 11 00:25:11.821410 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 11 00:25:12.028539 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 11 00:25:12.037397 (kubelet)[1861]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 11 00:25:12.110816 kubelet[1861]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 11 00:25:12.110816 kubelet[1861]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Sep 11 00:25:12.110816 kubelet[1861]: 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 11 00:25:12.111326 kubelet[1861]: I0911 00:25:12.110893 1861 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 11 00:25:12.472265 kubelet[1861]: I0911 00:25:12.472073 1861 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Sep 11 00:25:12.472265 kubelet[1861]: I0911 00:25:12.472126 1861 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 11 00:25:12.472808 kubelet[1861]: I0911 00:25:12.472502 1861 server.go:934] "Client rotation is on, will bootstrap in background" Sep 11 00:25:12.499523 kubelet[1861]: I0911 00:25:12.499315 1861 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 11 00:25:13.353206 kubelet[1861]: I0911 00:25:13.353166 1861 server.go:1431] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 11 00:25:13.353250 systemd-resolved[1399]: Clock change detected. Flushing caches. Sep 11 00:25:13.353716 systemd-timesyncd[1430]: Contacted time server 198.46.254.130:123 (1.flatcar.pool.ntp.org). Sep 11 00:25:13.353999 systemd-timesyncd[1430]: Initial clock synchronization to Thu 2025-09-11 00:25:13.353020 UTC. Sep 11 00:25:13.361072 kubelet[1861]: I0911 00:25:13.361034 1861 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 11 00:25:13.361408 kubelet[1861]: I0911 00:25:13.361384 1861 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Sep 11 00:25:13.361714 kubelet[1861]: I0911 00:25:13.361665 1861 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 11 00:25:13.362005 kubelet[1861]: I0911 00:25:13.361807 1861 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"144.126.208.231","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 11 00:25:13.362164 kubelet[1861]: I0911 00:25:13.362152 1861 topology_manager.go:138] "Creating topology manager with none policy" Sep 11 00:25:13.362226 kubelet[1861]: I0911 00:25:13.362213 1861 container_manager_linux.go:300] "Creating device plugin manager" Sep 11 00:25:13.362466 kubelet[1861]: I0911 00:25:13.362445 1861 state_mem.go:36] "Initialized new in-memory state store" Sep 11 00:25:13.364603 kubelet[1861]: I0911 00:25:13.364566 1861 kubelet.go:408] "Attempting to sync node with API server" Sep 11 00:25:13.365235 kubelet[1861]: I0911 00:25:13.364810 1861 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 11 00:25:13.365235 kubelet[1861]: I0911 00:25:13.364874 1861 kubelet.go:314] "Adding apiserver pod source" Sep 11 00:25:13.365235 kubelet[1861]: I0911 00:25:13.364904 1861 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 11 00:25:13.365235 kubelet[1861]: E0911 00:25:13.365097 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:13.365235 kubelet[1861]: E0911 00:25:13.365145 1861 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:13.367797 kubelet[1861]: I0911 00:25:13.367737 1861 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Sep 11 00:25:13.368509 kubelet[1861]: I0911 00:25:13.368490 1861 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 11 00:25:13.368658 kubelet[1861]: W0911 00:25:13.368647 1861 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 11 00:25:13.373012 kubelet[1861]: I0911 00:25:13.372966 1861 server.go:1274] "Started kubelet" Sep 11 00:25:13.382151 kubelet[1861]: I0911 00:25:13.381941 1861 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Sep 11 00:25:13.383824 kubelet[1861]: I0911 00:25:13.382632 1861 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 11 00:25:13.383824 kubelet[1861]: I0911 00:25:13.383151 1861 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 11 00:25:13.383824 kubelet[1861]: I0911 00:25:13.383712 1861 server.go:449] "Adding debug handlers to kubelet server" Sep 11 00:25:13.388407 kubelet[1861]: I0911 00:25:13.388370 1861 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 11 00:25:13.390115 kubelet[1861]: I0911 00:25:13.390052 1861 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 11 00:25:13.395931 kubelet[1861]: E0911 00:25:13.395875 1861 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 11 00:25:13.396823 kubelet[1861]: I0911 00:25:13.396678 1861 volume_manager.go:289] "Starting Kubelet Volume Manager" Sep 11 00:25:13.397472 kubelet[1861]: I0911 00:25:13.397206 1861 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Sep 11 00:25:13.397472 kubelet[1861]: I0911 00:25:13.397279 1861 reconciler.go:26] "Reconciler: start to sync state" Sep 11 00:25:13.397909 kubelet[1861]: I0911 00:25:13.397854 1861 factory.go:221] Registration of the systemd container factory successfully Sep 11 00:25:13.398007 kubelet[1861]: I0911 00:25:13.397970 1861 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 11 00:25:13.403829 kubelet[1861]: I0911 00:25:13.403491 1861 factory.go:221] Registration of the containerd container factory successfully Sep 11 00:25:13.404232 kubelet[1861]: E0911 00:25:13.404205 1861 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"144.126.208.231\" not found" Sep 11 00:25:13.413826 kubelet[1861]: E0911 00:25:13.412287 1861 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"144.126.208.231\" not found" node="144.126.208.231" Sep 11 00:25:13.438220 kubelet[1861]: I0911 00:25:13.438177 1861 cpu_manager.go:214] "Starting CPU manager" policy="none" Sep 11 00:25:13.438220 kubelet[1861]: I0911 00:25:13.438208 1861 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Sep 11 00:25:13.438520 kubelet[1861]: I0911 00:25:13.438234 1861 state_mem.go:36] "Initialized new in-memory state store" Sep 11 00:25:13.443310 kubelet[1861]: I0911 00:25:13.442904 1861 policy_none.go:49] "None policy: Start" Sep 11 00:25:13.445537 kubelet[1861]: I0911 00:25:13.445509 1861 memory_manager.go:170] "Starting memorymanager" policy="None" Sep 11 00:25:13.445998 kubelet[1861]: I0911 00:25:13.445674 1861 state_mem.go:35] "Initializing new in-memory state store" Sep 11 00:25:13.462047 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 11 00:25:13.480227 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 11 00:25:13.486547 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 11 00:25:13.494972 kubelet[1861]: I0911 00:25:13.494918 1861 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 11 00:25:13.495241 kubelet[1861]: I0911 00:25:13.495205 1861 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 11 00:25:13.495294 kubelet[1861]: I0911 00:25:13.495233 1861 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 11 00:25:13.504607 kubelet[1861]: I0911 00:25:13.504440 1861 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 11 00:25:13.506577 kubelet[1861]: I0911 00:25:13.506080 1861 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 11 00:25:13.506577 kubelet[1861]: E0911 00:25:13.506542 1861 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"144.126.208.231\" not found" Sep 11 00:25:13.507643 kubelet[1861]: I0911 00:25:13.507393 1861 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 11 00:25:13.507643 kubelet[1861]: I0911 00:25:13.507634 1861 status_manager.go:217] "Starting to sync pod status with apiserver" Sep 11 00:25:13.508827 kubelet[1861]: I0911 00:25:13.508104 1861 kubelet.go:2321] "Starting kubelet main sync loop" Sep 11 00:25:13.510035 kubelet[1861]: E0911 00:25:13.509350 1861 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 11 00:25:13.597439 kubelet[1861]: I0911 00:25:13.597153 1861 kubelet_node_status.go:72] "Attempting to register node" node="144.126.208.231" Sep 11 00:25:13.602786 kubelet[1861]: I0911 00:25:13.602710 1861 kubelet_node_status.go:75] "Successfully registered node" node="144.126.208.231" Sep 11 00:25:13.719819 kubelet[1861]: I0911 00:25:13.719781 1861 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 11 00:25:13.720370 containerd[1531]: time="2025-09-11T00:25:13.720235456Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 11 00:25:13.720827 kubelet[1861]: I0911 00:25:13.720552 1861 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 11 00:25:13.902567 sudo[1732]: pam_unix(sudo:session): session closed for user root Sep 11 00:25:13.907529 sshd[1731]: Connection closed by 147.75.109.163 port 46842 Sep 11 00:25:13.908013 sshd-session[1729]: pam_unix(sshd:session): session closed for user core Sep 11 00:25:13.911957 systemd[1]: sshd@4-144.126.208.231:22-147.75.109.163:46842.service: Deactivated successfully. Sep 11 00:25:13.915647 systemd[1]: session-5.scope: Deactivated successfully. Sep 11 00:25:13.916159 systemd[1]: session-5.scope: Consumed 706ms CPU time, 72.7M memory peak. Sep 11 00:25:13.919140 systemd-logind[1518]: Session 5 logged out. Waiting for processes to exit. Sep 11 00:25:13.920425 systemd-logind[1518]: Removed session 5. Sep 11 00:25:14.305126 kubelet[1861]: I0911 00:25:14.304940 1861 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 11 00:25:14.305643 kubelet[1861]: W0911 00:25:14.305197 1861 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 11 00:25:14.305643 kubelet[1861]: W0911 00:25:14.305502 1861 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 11 00:25:14.305643 kubelet[1861]: W0911 00:25:14.305529 1861 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 11 00:25:14.365424 kubelet[1861]: I0911 00:25:14.365345 1861 apiserver.go:52] "Watching apiserver" Sep 11 00:25:14.365794 kubelet[1861]: E0911 00:25:14.365738 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:14.383308 systemd[1]: Created slice kubepods-burstable-pod3d6aa928_7abd_410e_ba5e_994b33de5b9f.slice - libcontainer container kubepods-burstable-pod3d6aa928_7abd_410e_ba5e_994b33de5b9f.slice. Sep 11 00:25:14.400241 kubelet[1861]: I0911 00:25:14.400193 1861 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Sep 11 00:25:14.401311 kubelet[1861]: I0911 00:25:14.400902 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qx8wq\" (UniqueName: \"kubernetes.io/projected/62a75ba8-9d37-4235-a6a8-2a081a7fb64c-kube-api-access-qx8wq\") pod \"kube-proxy-mbdxs\" (UID: \"62a75ba8-9d37-4235-a6a8-2a081a7fb64c\") " pod="kube-system/kube-proxy-mbdxs" Sep 11 00:25:14.402051 kubelet[1861]: I0911 00:25:14.402007 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/3d6aa928-7abd-410e-ba5e-994b33de5b9f-cni-plugin\") pod \"kube-flannel-ds-bq65f\" (UID: \"3d6aa928-7abd-410e-ba5e-994b33de5b9f\") " pod="kube-flannel/kube-flannel-ds-bq65f" Sep 11 00:25:14.402276 kubelet[1861]: I0911 00:25:14.402209 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/3d6aa928-7abd-410e-ba5e-994b33de5b9f-flannel-cfg\") pod \"kube-flannel-ds-bq65f\" (UID: \"3d6aa928-7abd-410e-ba5e-994b33de5b9f\") " pod="kube-flannel/kube-flannel-ds-bq65f" Sep 11 00:25:14.402487 kubelet[1861]: I0911 00:25:14.402463 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3d6aa928-7abd-410e-ba5e-994b33de5b9f-xtables-lock\") pod \"kube-flannel-ds-bq65f\" (UID: \"3d6aa928-7abd-410e-ba5e-994b33de5b9f\") " pod="kube-flannel/kube-flannel-ds-bq65f" Sep 11 00:25:14.402642 kubelet[1861]: I0911 00:25:14.402624 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/62a75ba8-9d37-4235-a6a8-2a081a7fb64c-kube-proxy\") pod \"kube-proxy-mbdxs\" (UID: \"62a75ba8-9d37-4235-a6a8-2a081a7fb64c\") " pod="kube-system/kube-proxy-mbdxs" Sep 11 00:25:14.402855 kubelet[1861]: I0911 00:25:14.402784 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/62a75ba8-9d37-4235-a6a8-2a081a7fb64c-lib-modules\") pod \"kube-proxy-mbdxs\" (UID: \"62a75ba8-9d37-4235-a6a8-2a081a7fb64c\") " pod="kube-system/kube-proxy-mbdxs" Sep 11 00:25:14.402855 kubelet[1861]: I0911 00:25:14.402818 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/3d6aa928-7abd-410e-ba5e-994b33de5b9f-cni\") pod \"kube-flannel-ds-bq65f\" (UID: \"3d6aa928-7abd-410e-ba5e-994b33de5b9f\") " pod="kube-flannel/kube-flannel-ds-bq65f" Sep 11 00:25:14.403051 systemd[1]: Created slice kubepods-besteffort-pod62a75ba8_9d37_4235_a6a8_2a081a7fb64c.slice - libcontainer container kubepods-besteffort-pod62a75ba8_9d37_4235_a6a8_2a081a7fb64c.slice. Sep 11 00:25:14.404511 kubelet[1861]: I0911 00:25:14.403784 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vt97g\" (UniqueName: \"kubernetes.io/projected/3d6aa928-7abd-410e-ba5e-994b33de5b9f-kube-api-access-vt97g\") pod \"kube-flannel-ds-bq65f\" (UID: \"3d6aa928-7abd-410e-ba5e-994b33de5b9f\") " pod="kube-flannel/kube-flannel-ds-bq65f" Sep 11 00:25:14.404511 kubelet[1861]: I0911 00:25:14.403893 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/62a75ba8-9d37-4235-a6a8-2a081a7fb64c-xtables-lock\") pod \"kube-proxy-mbdxs\" (UID: \"62a75ba8-9d37-4235-a6a8-2a081a7fb64c\") " pod="kube-system/kube-proxy-mbdxs" Sep 11 00:25:14.404511 kubelet[1861]: I0911 00:25:14.403936 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/3d6aa928-7abd-410e-ba5e-994b33de5b9f-run\") pod \"kube-flannel-ds-bq65f\" (UID: \"3d6aa928-7abd-410e-ba5e-994b33de5b9f\") " pod="kube-flannel/kube-flannel-ds-bq65f" Sep 11 00:25:14.698009 kubelet[1861]: E0911 00:25:14.697849 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:14.699951 containerd[1531]: time="2025-09-11T00:25:14.699322143Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-bq65f,Uid:3d6aa928-7abd-410e-ba5e-994b33de5b9f,Namespace:kube-flannel,Attempt:0,}" Sep 11 00:25:14.714509 kubelet[1861]: E0911 00:25:14.714014 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:14.714970 containerd[1531]: time="2025-09-11T00:25:14.714920396Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-mbdxs,Uid:62a75ba8-9d37-4235-a6a8-2a081a7fb64c,Namespace:kube-system,Attempt:0,}" Sep 11 00:25:15.226569 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4245621410.mount: Deactivated successfully. Sep 11 00:25:15.237691 containerd[1531]: time="2025-09-11T00:25:15.237557831Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 11 00:25:15.243418 containerd[1531]: time="2025-09-11T00:25:15.243316341Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" Sep 11 00:25:15.243810 containerd[1531]: time="2025-09-11T00:25:15.243779403Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 11 00:25:15.245724 containerd[1531]: time="2025-09-11T00:25:15.245678344Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 11 00:25:15.247144 containerd[1531]: time="2025-09-11T00:25:15.247070579Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 11 00:25:15.249803 containerd[1531]: time="2025-09-11T00:25:15.249408254Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 11 00:25:15.251874 containerd[1531]: time="2025-09-11T00:25:15.251089406Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 543.173488ms" Sep 11 00:25:15.252010 containerd[1531]: time="2025-09-11T00:25:15.251953899Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 530.405034ms" Sep 11 00:25:15.292774 containerd[1531]: time="2025-09-11T00:25:15.292293012Z" level=info msg="connecting to shim 79628aba160fee28c21cd4928f1463bec65ce5a2b55018246ba9b295be49954b" address="unix:///run/containerd/s/abafda5ba71aa57dffffcac0e11ebc3c7d3fc07314539998ae3b6805fd80f051" namespace=k8s.io protocol=ttrpc version=3 Sep 11 00:25:15.294129 containerd[1531]: time="2025-09-11T00:25:15.294080677Z" level=info msg="connecting to shim b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634" address="unix:///run/containerd/s/5389081bae65b984e7e73747ed2e28fc68185fb87f5270146f675592ef44ec89" namespace=k8s.io protocol=ttrpc version=3 Sep 11 00:25:15.331192 systemd[1]: Started cri-containerd-b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634.scope - libcontainer container b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634. Sep 11 00:25:15.337790 systemd[1]: Started cri-containerd-79628aba160fee28c21cd4928f1463bec65ce5a2b55018246ba9b295be49954b.scope - libcontainer container 79628aba160fee28c21cd4928f1463bec65ce5a2b55018246ba9b295be49954b. Sep 11 00:25:15.366932 kubelet[1861]: E0911 00:25:15.366866 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:15.391493 containerd[1531]: time="2025-09-11T00:25:15.391438794Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-mbdxs,Uid:62a75ba8-9d37-4235-a6a8-2a081a7fb64c,Namespace:kube-system,Attempt:0,} returns sandbox id \"79628aba160fee28c21cd4928f1463bec65ce5a2b55018246ba9b295be49954b\"" Sep 11 00:25:15.394007 kubelet[1861]: E0911 00:25:15.393973 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:15.397164 containerd[1531]: time="2025-09-11T00:25:15.397105233Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.13\"" Sep 11 00:25:15.449435 containerd[1531]: time="2025-09-11T00:25:15.449358114Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-bq65f,Uid:3d6aa928-7abd-410e-ba5e-994b33de5b9f,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634\"" Sep 11 00:25:15.451300 kubelet[1861]: E0911 00:25:15.451101 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:16.367947 kubelet[1861]: E0911 00:25:16.367893 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:16.617675 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3160929348.mount: Deactivated successfully. Sep 11 00:25:17.221052 containerd[1531]: time="2025-09-11T00:25:17.220975713Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.13\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:17.222489 containerd[1531]: time="2025-09-11T00:25:17.222077857Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.13: active requests=0, bytes read=30410252" Sep 11 00:25:17.223343 containerd[1531]: time="2025-09-11T00:25:17.223303505Z" level=info msg="ImageCreate event name:\"sha256:21d97a49eeb0b08ecaba421a84a79ca44cf2bc57773c085bbfda537488790ad7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:17.226587 containerd[1531]: time="2025-09-11T00:25:17.226228747Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:a39637326e88d128d38da6ff2b2ceb4e856475887bfcb5f7a55734d4f63d9fae\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:17.227214 containerd[1531]: time="2025-09-11T00:25:17.227171765Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.13\" with image id \"sha256:21d97a49eeb0b08ecaba421a84a79ca44cf2bc57773c085bbfda537488790ad7\", repo tag \"registry.k8s.io/kube-proxy:v1.31.13\", repo digest \"registry.k8s.io/kube-proxy@sha256:a39637326e88d128d38da6ff2b2ceb4e856475887bfcb5f7a55734d4f63d9fae\", size \"30409271\" in 1.830010052s" Sep 11 00:25:17.227309 containerd[1531]: time="2025-09-11T00:25:17.227219206Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.13\" returns image reference \"sha256:21d97a49eeb0b08ecaba421a84a79ca44cf2bc57773c085bbfda537488790ad7\"" Sep 11 00:25:17.230821 containerd[1531]: time="2025-09-11T00:25:17.230299101Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 11 00:25:17.233705 containerd[1531]: time="2025-09-11T00:25:17.233611402Z" level=info msg="CreateContainer within sandbox \"79628aba160fee28c21cd4928f1463bec65ce5a2b55018246ba9b295be49954b\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 11 00:25:17.246687 containerd[1531]: time="2025-09-11T00:25:17.246632295Z" level=info msg="Container c4639221379b1183f31b0ee99997edc2328d29e0cf4c0ec1ce9b417d1b06d44b: CDI devices from CRI Config.CDIDevices: []" Sep 11 00:25:17.252045 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount330552790.mount: Deactivated successfully. Sep 11 00:25:17.264422 containerd[1531]: time="2025-09-11T00:25:17.264355643Z" level=info msg="CreateContainer within sandbox \"79628aba160fee28c21cd4928f1463bec65ce5a2b55018246ba9b295be49954b\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"c4639221379b1183f31b0ee99997edc2328d29e0cf4c0ec1ce9b417d1b06d44b\"" Sep 11 00:25:17.265625 containerd[1531]: time="2025-09-11T00:25:17.265532767Z" level=info msg="StartContainer for \"c4639221379b1183f31b0ee99997edc2328d29e0cf4c0ec1ce9b417d1b06d44b\"" Sep 11 00:25:17.268369 containerd[1531]: time="2025-09-11T00:25:17.268274596Z" level=info msg="connecting to shim c4639221379b1183f31b0ee99997edc2328d29e0cf4c0ec1ce9b417d1b06d44b" address="unix:///run/containerd/s/abafda5ba71aa57dffffcac0e11ebc3c7d3fc07314539998ae3b6805fd80f051" protocol=ttrpc version=3 Sep 11 00:25:17.308078 systemd[1]: Started cri-containerd-c4639221379b1183f31b0ee99997edc2328d29e0cf4c0ec1ce9b417d1b06d44b.scope - libcontainer container c4639221379b1183f31b0ee99997edc2328d29e0cf4c0ec1ce9b417d1b06d44b. Sep 11 00:25:17.369177 kubelet[1861]: E0911 00:25:17.369114 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:17.375631 containerd[1531]: time="2025-09-11T00:25:17.375517408Z" level=info msg="StartContainer for \"c4639221379b1183f31b0ee99997edc2328d29e0cf4c0ec1ce9b417d1b06d44b\" returns successfully" Sep 11 00:25:17.537618 kubelet[1861]: E0911 00:25:17.536692 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:18.369968 kubelet[1861]: E0911 00:25:18.369888 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:18.539367 kubelet[1861]: E0911 00:25:18.538876 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:19.163659 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount878479097.mount: Deactivated successfully. Sep 11 00:25:19.225410 containerd[1531]: time="2025-09-11T00:25:19.225340116Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:19.226851 containerd[1531]: time="2025-09-11T00:25:19.226498970Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Sep 11 00:25:19.227660 containerd[1531]: time="2025-09-11T00:25:19.227604309Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:19.230527 containerd[1531]: time="2025-09-11T00:25:19.230467894Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:19.232537 containerd[1531]: time="2025-09-11T00:25:19.232482221Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 2.002041504s" Sep 11 00:25:19.232537 containerd[1531]: time="2025-09-11T00:25:19.232536500Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Sep 11 00:25:19.235814 containerd[1531]: time="2025-09-11T00:25:19.235509588Z" level=info msg="CreateContainer within sandbox \"b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 11 00:25:19.247785 containerd[1531]: time="2025-09-11T00:25:19.246837943Z" level=info msg="Container c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52: CDI devices from CRI Config.CDIDevices: []" Sep 11 00:25:19.265524 containerd[1531]: time="2025-09-11T00:25:19.265426732Z" level=info msg="CreateContainer within sandbox \"b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52\"" Sep 11 00:25:19.268139 containerd[1531]: time="2025-09-11T00:25:19.266729140Z" level=info msg="StartContainer for \"c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52\"" Sep 11 00:25:19.268139 containerd[1531]: time="2025-09-11T00:25:19.267861219Z" level=info msg="connecting to shim c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52" address="unix:///run/containerd/s/5389081bae65b984e7e73747ed2e28fc68185fb87f5270146f675592ef44ec89" protocol=ttrpc version=3 Sep 11 00:25:19.306088 systemd[1]: Started cri-containerd-c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52.scope - libcontainer container c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52. Sep 11 00:25:19.350982 systemd[1]: cri-containerd-c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52.scope: Deactivated successfully. Sep 11 00:25:19.359925 containerd[1531]: time="2025-09-11T00:25:19.359792742Z" level=info msg="received exit event container_id:\"c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52\" id:\"c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52\" pid:2196 exited_at:{seconds:1757550319 nanos:359111052}" Sep 11 00:25:19.360228 containerd[1531]: time="2025-09-11T00:25:19.360094578Z" level=info msg="TaskExit event in podsandbox handler container_id:\"c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52\" id:\"c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52\" pid:2196 exited_at:{seconds:1757550319 nanos:359111052}" Sep 11 00:25:19.360556 containerd[1531]: time="2025-09-11T00:25:19.360521721Z" level=info msg="StartContainer for \"c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52\" returns successfully" Sep 11 00:25:19.370133 kubelet[1861]: E0911 00:25:19.370076 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:19.433234 systemd-resolved[1399]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. Sep 11 00:25:19.543481 kubelet[1861]: E0911 00:25:19.543370 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:19.544908 containerd[1531]: time="2025-09-11T00:25:19.544859361Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 11 00:25:19.557731 kubelet[1861]: I0911 00:25:19.557479 1861 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-mbdxs" podStartSLOduration=4.724876653 podStartE2EDuration="6.557448925s" podCreationTimestamp="2025-09-11 00:25:13 +0000 UTC" firstStartedPulling="2025-09-11 00:25:15.396483972 +0000 UTC m=+2.524293467" lastFinishedPulling="2025-09-11 00:25:17.229056244 +0000 UTC m=+4.356865739" observedRunningTime="2025-09-11 00:25:17.555438543 +0000 UTC m=+4.683248058" watchObservedRunningTime="2025-09-11 00:25:19.557448925 +0000 UTC m=+6.685258451" Sep 11 00:25:20.035205 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c3fb1080fec0ad8a9cf671cb7f3206f0b4a9b6d2a2935ba43e3f1d0237c81c52-rootfs.mount: Deactivated successfully. Sep 11 00:25:20.371212 kubelet[1861]: E0911 00:25:20.371026 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:21.371941 kubelet[1861]: E0911 00:25:21.371895 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:21.491879 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3595043624.mount: Deactivated successfully. Sep 11 00:25:22.372887 kubelet[1861]: E0911 00:25:22.372837 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:22.523394 containerd[1531]: time="2025-09-11T00:25:22.523321732Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:22.524708 containerd[1531]: time="2025-09-11T00:25:22.524392699Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Sep 11 00:25:22.525504 containerd[1531]: time="2025-09-11T00:25:22.525457955Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:22.528458 containerd[1531]: time="2025-09-11T00:25:22.528407504Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:22.529619 containerd[1531]: time="2025-09-11T00:25:22.529571382Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 2.984363134s" Sep 11 00:25:22.529831 containerd[1531]: time="2025-09-11T00:25:22.529795220Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Sep 11 00:25:22.532949 containerd[1531]: time="2025-09-11T00:25:22.532914028Z" level=info msg="CreateContainer within sandbox \"b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 11 00:25:22.545956 containerd[1531]: time="2025-09-11T00:25:22.545906672Z" level=info msg="Container 9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e: CDI devices from CRI Config.CDIDevices: []" Sep 11 00:25:22.548704 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1803250730.mount: Deactivated successfully. Sep 11 00:25:22.556484 containerd[1531]: time="2025-09-11T00:25:22.556324652Z" level=info msg="CreateContainer within sandbox \"b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e\"" Sep 11 00:25:22.557497 containerd[1531]: time="2025-09-11T00:25:22.557438076Z" level=info msg="StartContainer for \"9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e\"" Sep 11 00:25:22.558951 containerd[1531]: time="2025-09-11T00:25:22.558896753Z" level=info msg="connecting to shim 9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e" address="unix:///run/containerd/s/5389081bae65b984e7e73747ed2e28fc68185fb87f5270146f675592ef44ec89" protocol=ttrpc version=3 Sep 11 00:25:22.590066 systemd[1]: Started cri-containerd-9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e.scope - libcontainer container 9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e. Sep 11 00:25:22.640042 systemd[1]: cri-containerd-9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e.scope: Deactivated successfully. Sep 11 00:25:22.644023 containerd[1531]: time="2025-09-11T00:25:22.643941600Z" level=info msg="TaskExit event in podsandbox handler container_id:\"9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e\" id:\"9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e\" pid:2268 exited_at:{seconds:1757550322 nanos:642215439}" Sep 11 00:25:22.644356 containerd[1531]: time="2025-09-11T00:25:22.644221605Z" level=info msg="received exit event container_id:\"9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e\" id:\"9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e\" pid:2268 exited_at:{seconds:1757550322 nanos:642215439}" Sep 11 00:25:22.646714 containerd[1531]: time="2025-09-11T00:25:22.646666622Z" level=info msg="StartContainer for \"9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e\" returns successfully" Sep 11 00:25:22.679172 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9f121b511883664ff9651b84c54651e2ba6e13cf1c76538e2497c7b1a15eb54e-rootfs.mount: Deactivated successfully. Sep 11 00:25:22.712731 kubelet[1861]: I0911 00:25:22.712298 1861 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Sep 11 00:25:23.373912 kubelet[1861]: E0911 00:25:23.373854 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:23.558640 kubelet[1861]: E0911 00:25:23.558577 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:23.561270 containerd[1531]: time="2025-09-11T00:25:23.561207265Z" level=info msg="CreateContainer within sandbox \"b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 11 00:25:23.572678 containerd[1531]: time="2025-09-11T00:25:23.572616225Z" level=info msg="Container e9403d95d8e1d0a7680a5d10d5dfaa2cecf83ee88f90ffc3f33d094611a05fec: CDI devices from CRI Config.CDIDevices: []" Sep 11 00:25:23.576730 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1268017324.mount: Deactivated successfully. Sep 11 00:25:23.585435 containerd[1531]: time="2025-09-11T00:25:23.585361315Z" level=info msg="CreateContainer within sandbox \"b281d1228223a76334877510fe6bf26b9e3969c71236021db551425ecc8d2634\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"e9403d95d8e1d0a7680a5d10d5dfaa2cecf83ee88f90ffc3f33d094611a05fec\"" Sep 11 00:25:23.586796 containerd[1531]: time="2025-09-11T00:25:23.586598893Z" level=info msg="StartContainer for \"e9403d95d8e1d0a7680a5d10d5dfaa2cecf83ee88f90ffc3f33d094611a05fec\"" Sep 11 00:25:23.587790 containerd[1531]: time="2025-09-11T00:25:23.587676699Z" level=info msg="connecting to shim e9403d95d8e1d0a7680a5d10d5dfaa2cecf83ee88f90ffc3f33d094611a05fec" address="unix:///run/containerd/s/5389081bae65b984e7e73747ed2e28fc68185fb87f5270146f675592ef44ec89" protocol=ttrpc version=3 Sep 11 00:25:23.614007 systemd[1]: Started cri-containerd-e9403d95d8e1d0a7680a5d10d5dfaa2cecf83ee88f90ffc3f33d094611a05fec.scope - libcontainer container e9403d95d8e1d0a7680a5d10d5dfaa2cecf83ee88f90ffc3f33d094611a05fec. Sep 11 00:25:23.669932 containerd[1531]: time="2025-09-11T00:25:23.669838794Z" level=info msg="StartContainer for \"e9403d95d8e1d0a7680a5d10d5dfaa2cecf83ee88f90ffc3f33d094611a05fec\" returns successfully" Sep 11 00:25:24.374611 kubelet[1861]: E0911 00:25:24.374518 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:24.565276 kubelet[1861]: E0911 00:25:24.565240 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:24.744305 systemd-networkd[1449]: flannel.1: Link UP Sep 11 00:25:24.744311 systemd-networkd[1449]: flannel.1: Gained carrier Sep 11 00:25:25.119055 kubelet[1861]: I0911 00:25:25.118711 1861 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-bq65f" podStartSLOduration=5.039417325 podStartE2EDuration="12.118689955s" podCreationTimestamp="2025-09-11 00:25:13 +0000 UTC" firstStartedPulling="2025-09-11 00:25:15.451832876 +0000 UTC m=+2.579642372" lastFinishedPulling="2025-09-11 00:25:22.531105508 +0000 UTC m=+9.658915002" observedRunningTime="2025-09-11 00:25:24.581624972 +0000 UTC m=+11.709434501" watchObservedRunningTime="2025-09-11 00:25:25.118689955 +0000 UTC m=+12.246499447" Sep 11 00:25:25.128219 systemd[1]: Created slice kubepods-besteffort-pod5af94db7_4d11_4c6f_bbf4_76b8ecfcc7d8.slice - libcontainer container kubepods-besteffort-pod5af94db7_4d11_4c6f_bbf4_76b8ecfcc7d8.slice. Sep 11 00:25:25.225333 kubelet[1861]: I0911 00:25:25.225268 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bxjpn\" (UniqueName: \"kubernetes.io/projected/5af94db7-4d11-4c6f-bbf4-76b8ecfcc7d8-kube-api-access-bxjpn\") pod \"nginx-deployment-8587fbcb89-sdsnz\" (UID: \"5af94db7-4d11-4c6f-bbf4-76b8ecfcc7d8\") " pod="default/nginx-deployment-8587fbcb89-sdsnz" Sep 11 00:25:25.375499 kubelet[1861]: E0911 00:25:25.375344 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:25.434044 containerd[1531]: time="2025-09-11T00:25:25.433897716Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-sdsnz,Uid:5af94db7-4d11-4c6f-bbf4-76b8ecfcc7d8,Namespace:default,Attempt:0,}" Sep 11 00:25:25.453590 systemd-networkd[1449]: cni0: Link UP Sep 11 00:25:25.466915 systemd-networkd[1449]: veth879229c2: Link UP Sep 11 00:25:25.469321 kernel: cni0: port 1(veth879229c2) entered blocking state Sep 11 00:25:25.469423 kernel: cni0: port 1(veth879229c2) entered disabled state Sep 11 00:25:25.469455 kernel: veth879229c2: entered allmulticast mode Sep 11 00:25:25.470216 kernel: veth879229c2: entered promiscuous mode Sep 11 00:25:25.476087 kernel: cni0: port 1(veth879229c2) entered blocking state Sep 11 00:25:25.476564 kernel: cni0: port 1(veth879229c2) entered forwarding state Sep 11 00:25:25.476708 systemd-networkd[1449]: veth879229c2: Gained carrier Sep 11 00:25:25.477128 systemd-networkd[1449]: cni0: Gained carrier Sep 11 00:25:25.484662 containerd[1531]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Sep 11 00:25:25.484662 containerd[1531]: delegateAdd: netconf sent to delegate plugin: Sep 11 00:25:25.527097 containerd[1531]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-11T00:25:25.527020586Z" level=info msg="connecting to shim c5ca58eb9ade5bb8153da0622765500513eb5bd8f6c12449337e216a10dbbc63" address="unix:///run/containerd/s/2951926ce004663caebf24a8c42c5dfa07d0b11bf2fe452f9fd688c283b396b2" namespace=k8s.io protocol=ttrpc version=3 Sep 11 00:25:25.569402 kubelet[1861]: E0911 00:25:25.569357 1861 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.3 67.207.67.2 67.207.67.3" Sep 11 00:25:25.572139 systemd[1]: Started cri-containerd-c5ca58eb9ade5bb8153da0622765500513eb5bd8f6c12449337e216a10dbbc63.scope - libcontainer container c5ca58eb9ade5bb8153da0622765500513eb5bd8f6c12449337e216a10dbbc63. Sep 11 00:25:25.664065 containerd[1531]: time="2025-09-11T00:25:25.663914968Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-sdsnz,Uid:5af94db7-4d11-4c6f-bbf4-76b8ecfcc7d8,Namespace:default,Attempt:0,} returns sandbox id \"c5ca58eb9ade5bb8153da0622765500513eb5bd8f6c12449337e216a10dbbc63\"" Sep 11 00:25:25.666725 containerd[1531]: time="2025-09-11T00:25:25.666194710Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 11 00:25:25.668831 systemd-resolved[1399]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.2. Sep 11 00:25:26.345105 systemd-networkd[1449]: flannel.1: Gained IPv6LL Sep 11 00:25:26.376225 kubelet[1861]: E0911 00:25:26.376155 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:26.665005 systemd-networkd[1449]: cni0: Gained IPv6LL Sep 11 00:25:26.857157 systemd-networkd[1449]: veth879229c2: Gained IPv6LL Sep 11 00:25:27.377475 kubelet[1861]: E0911 00:25:27.376886 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:28.377481 kubelet[1861]: E0911 00:25:28.377420 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:28.864005 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3940910365.mount: Deactivated successfully. Sep 11 00:25:29.378274 kubelet[1861]: E0911 00:25:29.378181 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:30.378791 kubelet[1861]: E0911 00:25:30.378712 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:30.630621 containerd[1531]: time="2025-09-11T00:25:30.630318502Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:30.632495 containerd[1531]: time="2025-09-11T00:25:30.632251660Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73307810" Sep 11 00:25:30.634968 containerd[1531]: time="2025-09-11T00:25:30.634904882Z" level=info msg="ImageCreate event name:\"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:30.639009 containerd[1531]: time="2025-09-11T00:25:30.638907354Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:30.640670 containerd[1531]: time="2025-09-11T00:25:30.640473511Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"73307688\" in 4.974191165s" Sep 11 00:25:30.640670 containerd[1531]: time="2025-09-11T00:25:30.640543380Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\"" Sep 11 00:25:30.645033 containerd[1531]: time="2025-09-11T00:25:30.644312507Z" level=info msg="CreateContainer within sandbox \"c5ca58eb9ade5bb8153da0622765500513eb5bd8f6c12449337e216a10dbbc63\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 11 00:25:30.657826 containerd[1531]: time="2025-09-11T00:25:30.656239949Z" level=info msg="Container 3087363c8643d52a4d72a6fbee5e5ef24a802d69d6e549d76fb4ed57b2bcbab5: CDI devices from CRI Config.CDIDevices: []" Sep 11 00:25:30.671552 containerd[1531]: time="2025-09-11T00:25:30.671447654Z" level=info msg="CreateContainer within sandbox \"c5ca58eb9ade5bb8153da0622765500513eb5bd8f6c12449337e216a10dbbc63\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"3087363c8643d52a4d72a6fbee5e5ef24a802d69d6e549d76fb4ed57b2bcbab5\"" Sep 11 00:25:30.673881 containerd[1531]: time="2025-09-11T00:25:30.672835185Z" level=info msg="StartContainer for \"3087363c8643d52a4d72a6fbee5e5ef24a802d69d6e549d76fb4ed57b2bcbab5\"" Sep 11 00:25:30.674548 containerd[1531]: time="2025-09-11T00:25:30.674504596Z" level=info msg="connecting to shim 3087363c8643d52a4d72a6fbee5e5ef24a802d69d6e549d76fb4ed57b2bcbab5" address="unix:///run/containerd/s/2951926ce004663caebf24a8c42c5dfa07d0b11bf2fe452f9fd688c283b396b2" protocol=ttrpc version=3 Sep 11 00:25:30.710081 systemd[1]: Started cri-containerd-3087363c8643d52a4d72a6fbee5e5ef24a802d69d6e549d76fb4ed57b2bcbab5.scope - libcontainer container 3087363c8643d52a4d72a6fbee5e5ef24a802d69d6e549d76fb4ed57b2bcbab5. Sep 11 00:25:30.757998 containerd[1531]: time="2025-09-11T00:25:30.757907067Z" level=info msg="StartContainer for \"3087363c8643d52a4d72a6fbee5e5ef24a802d69d6e549d76fb4ed57b2bcbab5\" returns successfully" Sep 11 00:25:31.380402 kubelet[1861]: E0911 00:25:31.380306 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:31.595048 kubelet[1861]: I0911 00:25:31.594897 1861 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-sdsnz" podStartSLOduration=1.6185241320000001 podStartE2EDuration="6.594872683s" podCreationTimestamp="2025-09-11 00:25:25 +0000 UTC" firstStartedPulling="2025-09-11 00:25:25.665526108 +0000 UTC m=+12.793335600" lastFinishedPulling="2025-09-11 00:25:30.64187465 +0000 UTC m=+17.769684151" observedRunningTime="2025-09-11 00:25:31.594559609 +0000 UTC m=+18.722369131" watchObservedRunningTime="2025-09-11 00:25:31.594872683 +0000 UTC m=+18.722682205" Sep 11 00:25:32.381205 kubelet[1861]: E0911 00:25:32.381101 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:33.365117 kubelet[1861]: E0911 00:25:33.365031 1861 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:33.381456 kubelet[1861]: E0911 00:25:33.381337 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:34.382000 kubelet[1861]: E0911 00:25:34.381832 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:35.382911 kubelet[1861]: E0911 00:25:35.382822 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:36.383504 kubelet[1861]: E0911 00:25:36.383414 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:37.383731 kubelet[1861]: E0911 00:25:37.383655 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:37.787783 systemd[1]: Created slice kubepods-besteffort-podfd5abe6b_e66b_4cca_97f9_d2c678ed453c.slice - libcontainer container kubepods-besteffort-podfd5abe6b_e66b_4cca_97f9_d2c678ed453c.slice. Sep 11 00:25:37.911971 kubelet[1861]: I0911 00:25:37.911906 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/fd5abe6b-e66b-4cca-97f9-d2c678ed453c-data\") pod \"nfs-server-provisioner-0\" (UID: \"fd5abe6b-e66b-4cca-97f9-d2c678ed453c\") " pod="default/nfs-server-provisioner-0" Sep 11 00:25:37.912268 kubelet[1861]: I0911 00:25:37.912247 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qxp4j\" (UniqueName: \"kubernetes.io/projected/fd5abe6b-e66b-4cca-97f9-d2c678ed453c-kube-api-access-qxp4j\") pod \"nfs-server-provisioner-0\" (UID: \"fd5abe6b-e66b-4cca-97f9-d2c678ed453c\") " pod="default/nfs-server-provisioner-0" Sep 11 00:25:38.092986 containerd[1531]: time="2025-09-11T00:25:38.092796871Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fd5abe6b-e66b-4cca-97f9-d2c678ed453c,Namespace:default,Attempt:0,}" Sep 11 00:25:38.111035 systemd-networkd[1449]: veth06d7224c: Link UP Sep 11 00:25:38.114812 kernel: cni0: port 2(veth06d7224c) entered blocking state Sep 11 00:25:38.114957 kernel: cni0: port 2(veth06d7224c) entered disabled state Sep 11 00:25:38.117719 kernel: veth06d7224c: entered allmulticast mode Sep 11 00:25:38.117853 kernel: veth06d7224c: entered promiscuous mode Sep 11 00:25:38.131809 kernel: cni0: port 2(veth06d7224c) entered blocking state Sep 11 00:25:38.131964 kernel: cni0: port 2(veth06d7224c) entered forwarding state Sep 11 00:25:38.132314 systemd-networkd[1449]: veth06d7224c: Gained carrier Sep 11 00:25:38.135992 containerd[1531]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000018938), "name":"cbr0", "type":"bridge"} Sep 11 00:25:38.135992 containerd[1531]: delegateAdd: netconf sent to delegate plugin: Sep 11 00:25:38.178020 containerd[1531]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-11T00:25:38.177941926Z" level=info msg="connecting to shim 20b1d9b13aff6c1a3beff2331398c72aad1297cc95edd7e7bdc2e8b6716ee5ac" address="unix:///run/containerd/s/147cb87e8f0e7d1819e3f6eb784fe58d277fee5eaee8d85bb1471ebfcff50a89" namespace=k8s.io protocol=ttrpc version=3 Sep 11 00:25:38.235167 systemd[1]: Started cri-containerd-20b1d9b13aff6c1a3beff2331398c72aad1297cc95edd7e7bdc2e8b6716ee5ac.scope - libcontainer container 20b1d9b13aff6c1a3beff2331398c72aad1297cc95edd7e7bdc2e8b6716ee5ac. Sep 11 00:25:38.314157 containerd[1531]: time="2025-09-11T00:25:38.314066242Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:fd5abe6b-e66b-4cca-97f9-d2c678ed453c,Namespace:default,Attempt:0,} returns sandbox id \"20b1d9b13aff6c1a3beff2331398c72aad1297cc95edd7e7bdc2e8b6716ee5ac\"" Sep 11 00:25:38.317017 containerd[1531]: time="2025-09-11T00:25:38.316954029Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 11 00:25:38.384542 kubelet[1861]: E0911 00:25:38.384342 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:39.385446 kubelet[1861]: E0911 00:25:39.385351 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:39.784979 systemd-networkd[1449]: veth06d7224c: Gained IPv6LL Sep 11 00:25:40.386127 kubelet[1861]: E0911 00:25:40.386074 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:40.940078 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1223273630.mount: Deactivated successfully. Sep 11 00:25:41.387975 kubelet[1861]: E0911 00:25:41.387517 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:42.388551 kubelet[1861]: E0911 00:25:42.388482 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:43.389108 kubelet[1861]: E0911 00:25:43.389005 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:43.737221 containerd[1531]: time="2025-09-11T00:25:43.736884190Z" 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 11 00:25:43.739683 containerd[1531]: time="2025-09-11T00:25:43.739618192Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Sep 11 00:25:43.740658 containerd[1531]: time="2025-09-11T00:25:43.740532255Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:43.744947 containerd[1531]: time="2025-09-11T00:25:43.744852244Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:43.746848 containerd[1531]: time="2025-09-11T00:25:43.745822430Z" 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 5.428803143s" Sep 11 00:25:43.746848 containerd[1531]: time="2025-09-11T00:25:43.745899882Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Sep 11 00:25:43.750487 containerd[1531]: time="2025-09-11T00:25:43.750324826Z" level=info msg="CreateContainer within sandbox \"20b1d9b13aff6c1a3beff2331398c72aad1297cc95edd7e7bdc2e8b6716ee5ac\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 11 00:25:43.764138 containerd[1531]: time="2025-09-11T00:25:43.764080096Z" level=info msg="Container 975870ec7dfec5257a52e8ca570ae7ca276050ee169f423a2d9194d6aeccf1f3: CDI devices from CRI Config.CDIDevices: []" Sep 11 00:25:43.770342 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1400376857.mount: Deactivated successfully. Sep 11 00:25:43.780936 containerd[1531]: time="2025-09-11T00:25:43.780859906Z" level=info msg="CreateContainer within sandbox \"20b1d9b13aff6c1a3beff2331398c72aad1297cc95edd7e7bdc2e8b6716ee5ac\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"975870ec7dfec5257a52e8ca570ae7ca276050ee169f423a2d9194d6aeccf1f3\"" Sep 11 00:25:43.782476 containerd[1531]: time="2025-09-11T00:25:43.782315996Z" level=info msg="StartContainer for \"975870ec7dfec5257a52e8ca570ae7ca276050ee169f423a2d9194d6aeccf1f3\"" Sep 11 00:25:43.784063 containerd[1531]: time="2025-09-11T00:25:43.784012274Z" level=info msg="connecting to shim 975870ec7dfec5257a52e8ca570ae7ca276050ee169f423a2d9194d6aeccf1f3" address="unix:///run/containerd/s/147cb87e8f0e7d1819e3f6eb784fe58d277fee5eaee8d85bb1471ebfcff50a89" protocol=ttrpc version=3 Sep 11 00:25:43.816111 systemd[1]: Started cri-containerd-975870ec7dfec5257a52e8ca570ae7ca276050ee169f423a2d9194d6aeccf1f3.scope - libcontainer container 975870ec7dfec5257a52e8ca570ae7ca276050ee169f423a2d9194d6aeccf1f3. Sep 11 00:25:43.866813 containerd[1531]: time="2025-09-11T00:25:43.866678536Z" level=info msg="StartContainer for \"975870ec7dfec5257a52e8ca570ae7ca276050ee169f423a2d9194d6aeccf1f3\" returns successfully" Sep 11 00:25:44.389349 kubelet[1861]: E0911 00:25:44.389271 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:44.634277 kubelet[1861]: I0911 00:25:44.634085 1861 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.202140605 podStartE2EDuration="7.634067995s" podCreationTimestamp="2025-09-11 00:25:37 +0000 UTC" firstStartedPulling="2025-09-11 00:25:38.316341161 +0000 UTC m=+25.444150656" lastFinishedPulling="2025-09-11 00:25:43.748268519 +0000 UTC m=+30.876078046" observedRunningTime="2025-09-11 00:25:44.633874614 +0000 UTC m=+31.761684145" watchObservedRunningTime="2025-09-11 00:25:44.634067995 +0000 UTC m=+31.761877903" Sep 11 00:25:45.390512 kubelet[1861]: E0911 00:25:45.390435 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:46.391595 kubelet[1861]: E0911 00:25:46.391518 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:47.392228 kubelet[1861]: E0911 00:25:47.392151 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:48.393206 kubelet[1861]: E0911 00:25:48.393109 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:49.394544 kubelet[1861]: E0911 00:25:49.394378 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:50.395512 kubelet[1861]: E0911 00:25:50.395445 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:51.396413 kubelet[1861]: E0911 00:25:51.396333 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:52.397468 kubelet[1861]: E0911 00:25:52.397384 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:52.849880 update_engine[1519]: I20250911 00:25:52.849630 1519 update_attempter.cc:509] Updating boot flags... Sep 11 00:25:53.365781 kubelet[1861]: E0911 00:25:53.365658 1861 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:53.398537 kubelet[1861]: E0911 00:25:53.398389 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:53.768439 systemd[1]: Created slice kubepods-besteffort-podfcaf41dc_a269_4de2_93c0_fe1aa0e5b615.slice - libcontainer container kubepods-besteffort-podfcaf41dc_a269_4de2_93c0_fe1aa0e5b615.slice. Sep 11 00:25:53.931533 kubelet[1861]: I0911 00:25:53.931191 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-nlczz\" (UniqueName: \"kubernetes.io/projected/fcaf41dc-a269-4de2-93c0-fe1aa0e5b615-kube-api-access-nlczz\") pod \"test-pod-1\" (UID: \"fcaf41dc-a269-4de2-93c0-fe1aa0e5b615\") " pod="default/test-pod-1" Sep 11 00:25:53.931533 kubelet[1861]: I0911 00:25:53.931251 1861 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-fcce57fc-c8ae-4c44-9c87-f12d00e60bc7\" (UniqueName: \"kubernetes.io/nfs/fcaf41dc-a269-4de2-93c0-fe1aa0e5b615-pvc-fcce57fc-c8ae-4c44-9c87-f12d00e60bc7\") pod \"test-pod-1\" (UID: \"fcaf41dc-a269-4de2-93c0-fe1aa0e5b615\") " pod="default/test-pod-1" Sep 11 00:25:54.079863 kernel: netfs: FS-Cache loaded Sep 11 00:25:54.157013 kernel: RPC: Registered named UNIX socket transport module. Sep 11 00:25:54.157200 kernel: RPC: Registered udp transport module. Sep 11 00:25:54.157233 kernel: RPC: Registered tcp transport module. Sep 11 00:25:54.157261 kernel: RPC: Registered tcp-with-tls transport module. Sep 11 00:25:54.157866 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 11 00:25:54.399648 kubelet[1861]: E0911 00:25:54.399562 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:54.418081 kernel: NFS: Registering the id_resolver key type Sep 11 00:25:54.418236 kernel: Key type id_resolver registered Sep 11 00:25:54.418259 kernel: Key type id_legacy registered Sep 11 00:25:54.456642 nfsidmap[2897]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '1.0-n-33704dafec' Sep 11 00:25:54.462153 nfsidmap[2898]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '1.0-n-33704dafec' Sep 11 00:25:54.479201 nfsrahead[2900]: setting /var/lib/kubelet/pods/fcaf41dc-a269-4de2-93c0-fe1aa0e5b615/volumes/kubernetes.io~nfs/pvc-fcce57fc-c8ae-4c44-9c87-f12d00e60bc7 readahead to 128 Sep 11 00:25:54.674486 containerd[1531]: time="2025-09-11T00:25:54.674335921Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:fcaf41dc-a269-4de2-93c0-fe1aa0e5b615,Namespace:default,Attempt:0,}" Sep 11 00:25:54.696083 systemd-networkd[1449]: vethb6694a44: Link UP Sep 11 00:25:54.699815 kernel: cni0: port 3(vethb6694a44) entered blocking state Sep 11 00:25:54.699917 kernel: cni0: port 3(vethb6694a44) entered disabled state Sep 11 00:25:54.703476 kernel: vethb6694a44: entered allmulticast mode Sep 11 00:25:54.703614 kernel: vethb6694a44: entered promiscuous mode Sep 11 00:25:54.716121 kernel: cni0: port 3(vethb6694a44) entered blocking state Sep 11 00:25:54.716275 kernel: cni0: port 3(vethb6694a44) entered forwarding state Sep 11 00:25:54.716615 systemd-networkd[1449]: vethb6694a44: Gained carrier Sep 11 00:25:54.719808 containerd[1531]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000020938), "name":"cbr0", "type":"bridge"} Sep 11 00:25:54.719808 containerd[1531]: delegateAdd: netconf sent to delegate plugin: Sep 11 00:25:54.774265 containerd[1531]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-11T00:25:54.774214839Z" level=info msg="connecting to shim 52900e3930fe9d34fea98249f86e9ff6f2fdbc397d9b9bfdc345c0698280f41b" address="unix:///run/containerd/s/ff8c1abb3f5d26eb9322d1d05e9e06c022cce451d5a06abc4bdee917f8cdd4da" namespace=k8s.io protocol=ttrpc version=3 Sep 11 00:25:54.806103 systemd[1]: Started cri-containerd-52900e3930fe9d34fea98249f86e9ff6f2fdbc397d9b9bfdc345c0698280f41b.scope - libcontainer container 52900e3930fe9d34fea98249f86e9ff6f2fdbc397d9b9bfdc345c0698280f41b. Sep 11 00:25:54.906678 containerd[1531]: time="2025-09-11T00:25:54.906594297Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:fcaf41dc-a269-4de2-93c0-fe1aa0e5b615,Namespace:default,Attempt:0,} returns sandbox id \"52900e3930fe9d34fea98249f86e9ff6f2fdbc397d9b9bfdc345c0698280f41b\"" Sep 11 00:25:54.912527 containerd[1531]: time="2025-09-11T00:25:54.912174182Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 11 00:25:55.288794 containerd[1531]: time="2025-09-11T00:25:55.288635404Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 11 00:25:55.289979 containerd[1531]: time="2025-09-11T00:25:55.289918642Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 11 00:25:55.294992 containerd[1531]: time="2025-09-11T00:25:55.294872408Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"73307688\" in 382.655282ms" Sep 11 00:25:55.294992 containerd[1531]: time="2025-09-11T00:25:55.294937299Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\"" Sep 11 00:25:55.298036 containerd[1531]: time="2025-09-11T00:25:55.297988033Z" level=info msg="CreateContainer within sandbox \"52900e3930fe9d34fea98249f86e9ff6f2fdbc397d9b9bfdc345c0698280f41b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 11 00:25:55.312805 containerd[1531]: time="2025-09-11T00:25:55.311100722Z" level=info msg="Container b2696f1dcd935d10f220b4b282939481f353537c11751ab6a1c0e7260efc28d2: CDI devices from CRI Config.CDIDevices: []" Sep 11 00:25:55.326519 containerd[1531]: time="2025-09-11T00:25:55.326364522Z" level=info msg="CreateContainer within sandbox \"52900e3930fe9d34fea98249f86e9ff6f2fdbc397d9b9bfdc345c0698280f41b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"b2696f1dcd935d10f220b4b282939481f353537c11751ab6a1c0e7260efc28d2\"" Sep 11 00:25:55.327646 containerd[1531]: time="2025-09-11T00:25:55.327610014Z" level=info msg="StartContainer for \"b2696f1dcd935d10f220b4b282939481f353537c11751ab6a1c0e7260efc28d2\"" Sep 11 00:25:55.328788 containerd[1531]: time="2025-09-11T00:25:55.328724811Z" level=info msg="connecting to shim b2696f1dcd935d10f220b4b282939481f353537c11751ab6a1c0e7260efc28d2" address="unix:///run/containerd/s/ff8c1abb3f5d26eb9322d1d05e9e06c022cce451d5a06abc4bdee917f8cdd4da" protocol=ttrpc version=3 Sep 11 00:25:55.363164 systemd[1]: Started cri-containerd-b2696f1dcd935d10f220b4b282939481f353537c11751ab6a1c0e7260efc28d2.scope - libcontainer container b2696f1dcd935d10f220b4b282939481f353537c11751ab6a1c0e7260efc28d2. Sep 11 00:25:55.401234 kubelet[1861]: E0911 00:25:55.401084 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:55.427091 containerd[1531]: time="2025-09-11T00:25:55.427039328Z" level=info msg="StartContainer for \"b2696f1dcd935d10f220b4b282939481f353537c11751ab6a1c0e7260efc28d2\" returns successfully" Sep 11 00:25:56.401946 kubelet[1861]: E0911 00:25:56.401860 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:56.425285 systemd-networkd[1449]: vethb6694a44: Gained IPv6LL Sep 11 00:25:57.402506 kubelet[1861]: E0911 00:25:57.402331 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:58.403334 kubelet[1861]: E0911 00:25:58.403253 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:25:59.404337 kubelet[1861]: E0911 00:25:59.404263 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:26:00.404956 kubelet[1861]: E0911 00:26:00.404874 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 11 00:26:01.405343 kubelet[1861]: E0911 00:26:01.405268 1861 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"