Sep 16 04:58:23.918195 kernel: Linux version 6.12.47-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue Sep 16 03:05:42 -00 2025 Sep 16 04:58:23.918225 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=0b876f86a632750e9937176808a48c2452d5168964273bcfc3c72f2a26140c06 Sep 16 04:58:23.918236 kernel: BIOS-provided physical RAM map: Sep 16 04:58:23.919321 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 16 04:58:23.919335 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 16 04:58:23.919343 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 16 04:58:23.919351 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdafff] usable Sep 16 04:58:23.919363 kernel: BIOS-e820: [mem 0x000000007ffdb000-0x000000007fffffff] reserved Sep 16 04:58:23.919376 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 16 04:58:23.919382 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 16 04:58:23.919390 kernel: NX (Execute Disable) protection: active Sep 16 04:58:23.919396 kernel: APIC: Static calls initialized Sep 16 04:58:23.919403 kernel: SMBIOS 2.8 present. Sep 16 04:58:23.919411 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 Sep 16 04:58:23.919422 kernel: DMI: Memory slots populated: 1/1 Sep 16 04:58:23.919430 kernel: Hypervisor detected: KVM Sep 16 04:58:23.919443 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 16 04:58:23.919454 kernel: kvm-clock: using sched offset of 4729423884 cycles Sep 16 04:58:23.919463 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 16 04:58:23.919471 kernel: tsc: Detected 1995.312 MHz processor Sep 16 04:58:23.919479 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 16 04:58:23.919487 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 16 04:58:23.919495 kernel: last_pfn = 0x7ffdb max_arch_pfn = 0x400000000 Sep 16 04:58:23.919505 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 16 04:58:23.919513 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 16 04:58:23.919521 kernel: ACPI: Early table checksum verification disabled Sep 16 04:58:23.919528 kernel: ACPI: RSDP 0x00000000000F5950 000014 (v00 BOCHS ) Sep 16 04:58:23.919543 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 16 04:58:23.919551 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 16 04:58:23.919558 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 16 04:58:23.919566 kernel: ACPI: FACS 0x000000007FFE0000 000040 Sep 16 04:58:23.919573 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 16 04:58:23.919583 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 16 04:58:23.919591 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 16 04:58:23.919598 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 16 04:58:23.919606 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] Sep 16 04:58:23.919613 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] Sep 16 04:58:23.919621 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Sep 16 04:58:23.919629 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] Sep 16 04:58:23.919637 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] Sep 16 04:58:23.919650 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] Sep 16 04:58:23.919658 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] Sep 16 04:58:23.919666 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Sep 16 04:58:23.919674 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Sep 16 04:58:23.919693 kernel: NUMA: Node 0 [mem 0x00001000-0x0009ffff] + [mem 0x00100000-0x7ffdafff] -> [mem 0x00001000-0x7ffdafff] Sep 16 04:58:23.919701 kernel: NODE_DATA(0) allocated [mem 0x7ffd3dc0-0x7ffdafff] Sep 16 04:58:23.919711 kernel: Zone ranges: Sep 16 04:58:23.919719 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 16 04:58:23.919727 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdafff] Sep 16 04:58:23.919734 kernel: Normal empty Sep 16 04:58:23.919742 kernel: Device empty Sep 16 04:58:23.919750 kernel: Movable zone start for each node Sep 16 04:58:23.919761 kernel: Early memory node ranges Sep 16 04:58:23.919769 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 16 04:58:23.919776 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdafff] Sep 16 04:58:23.919787 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdafff] Sep 16 04:58:23.919795 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 16 04:58:23.919803 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 16 04:58:23.919811 kernel: On node 0, zone DMA32: 37 pages in unavailable ranges Sep 16 04:58:23.919818 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 16 04:58:23.919826 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 16 04:58:23.919839 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 16 04:58:23.919847 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 16 04:58:23.919858 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 16 04:58:23.919869 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 16 04:58:23.919877 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 16 04:58:23.919888 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 16 04:58:23.919896 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 16 04:58:23.919904 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 16 04:58:23.919912 kernel: TSC deadline timer available Sep 16 04:58:23.919919 kernel: CPU topo: Max. logical packages: 1 Sep 16 04:58:23.919927 kernel: CPU topo: Max. logical dies: 1 Sep 16 04:58:23.919935 kernel: CPU topo: Max. dies per package: 1 Sep 16 04:58:23.919943 kernel: CPU topo: Max. threads per core: 1 Sep 16 04:58:23.919953 kernel: CPU topo: Num. cores per package: 2 Sep 16 04:58:23.919960 kernel: CPU topo: Num. threads per package: 2 Sep 16 04:58:23.919968 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Sep 16 04:58:23.919975 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 16 04:58:23.919983 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Sep 16 04:58:23.919991 kernel: Booting paravirtualized kernel on KVM Sep 16 04:58:23.919999 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 16 04:58:23.920007 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Sep 16 04:58:23.920015 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576 Sep 16 04:58:23.920026 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152 Sep 16 04:58:23.920033 kernel: pcpu-alloc: [0] 0 1 Sep 16 04:58:23.920041 kernel: kvm-guest: PV spinlocks disabled, no host support Sep 16 04:58:23.920051 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=0b876f86a632750e9937176808a48c2452d5168964273bcfc3c72f2a26140c06 Sep 16 04:58:23.920059 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 16 04:58:23.920067 kernel: random: crng init done Sep 16 04:58:23.920075 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 16 04:58:23.920083 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 16 04:58:23.920094 kernel: Fallback order for Node 0: 0 Sep 16 04:58:23.920102 kernel: Built 1 zonelists, mobility grouping on. Total pages: 524153 Sep 16 04:58:23.920109 kernel: Policy zone: DMA32 Sep 16 04:58:23.920117 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 16 04:58:23.920125 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 16 04:58:23.920133 kernel: Kernel/User page tables isolation: enabled Sep 16 04:58:23.920141 kernel: ftrace: allocating 40125 entries in 157 pages Sep 16 04:58:23.920148 kernel: ftrace: allocated 157 pages with 5 groups Sep 16 04:58:23.920156 kernel: Dynamic Preempt: voluntary Sep 16 04:58:23.920167 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 16 04:58:23.920182 kernel: rcu: RCU event tracing is enabled. Sep 16 04:58:23.920191 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 16 04:58:23.920198 kernel: Trampoline variant of Tasks RCU enabled. Sep 16 04:58:23.920207 kernel: Rude variant of Tasks RCU enabled. Sep 16 04:58:23.920214 kernel: Tracing variant of Tasks RCU enabled. Sep 16 04:58:23.920222 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 16 04:58:23.920230 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 16 04:58:23.920238 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 16 04:58:23.920267 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 16 04:58:23.920276 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 16 04:58:23.920288 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Sep 16 04:58:23.920305 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 16 04:58:23.920316 kernel: Console: colour VGA+ 80x25 Sep 16 04:58:23.920327 kernel: printk: legacy console [tty0] enabled Sep 16 04:58:23.920339 kernel: printk: legacy console [ttyS0] enabled Sep 16 04:58:23.920350 kernel: ACPI: Core revision 20240827 Sep 16 04:58:23.920362 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 16 04:58:23.920388 kernel: APIC: Switch to symmetric I/O mode setup Sep 16 04:58:23.920401 kernel: x2apic enabled Sep 16 04:58:23.920414 kernel: APIC: Switched APIC routing to: physical x2apic Sep 16 04:58:23.920429 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 16 04:58:23.920447 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x3985c314e25, max_idle_ns: 881590612270 ns Sep 16 04:58:23.920468 kernel: Calibrating delay loop (skipped) preset value.. 3990.62 BogoMIPS (lpj=1995312) Sep 16 04:58:23.920480 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 16 04:58:23.920492 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 16 04:58:23.920505 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 16 04:58:23.920522 kernel: Spectre V2 : Mitigation: Retpolines Sep 16 04:58:23.920535 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 16 04:58:23.920549 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Sep 16 04:58:23.920562 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 16 04:58:23.920576 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 16 04:58:23.920590 kernel: MDS: Mitigation: Clear CPU buffers Sep 16 04:58:23.920607 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 16 04:58:23.920619 kernel: active return thunk: its_return_thunk Sep 16 04:58:23.920629 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 16 04:58:23.920645 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 16 04:58:23.920660 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 16 04:58:23.920672 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 16 04:58:23.920685 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 16 04:58:23.920697 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 16 04:58:23.920708 kernel: Freeing SMP alternatives memory: 32K Sep 16 04:58:23.920720 kernel: pid_max: default: 32768 minimum: 301 Sep 16 04:58:23.920843 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 16 04:58:23.920856 kernel: landlock: Up and running. Sep 16 04:58:23.920871 kernel: SELinux: Initializing. Sep 16 04:58:23.920883 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Sep 16 04:58:23.920892 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Sep 16 04:58:23.920910 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) Sep 16 04:58:23.920921 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 16 04:58:23.920929 kernel: signal: max sigframe size: 1776 Sep 16 04:58:23.920938 kernel: rcu: Hierarchical SRCU implementation. Sep 16 04:58:23.920951 kernel: rcu: Max phase no-delay instances is 400. Sep 16 04:58:23.920960 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 16 04:58:23.920968 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 16 04:58:23.920977 kernel: smp: Bringing up secondary CPUs ... Sep 16 04:58:23.920991 kernel: smpboot: x86: Booting SMP configuration: Sep 16 04:58:23.921000 kernel: .... node #0, CPUs: #1 Sep 16 04:58:23.921009 kernel: smp: Brought up 1 node, 2 CPUs Sep 16 04:58:23.921018 kernel: smpboot: Total of 2 processors activated (7981.24 BogoMIPS) Sep 16 04:58:23.921027 kernel: Memory: 1966916K/2096612K available (14336K kernel code, 2432K rwdata, 9992K rodata, 54096K init, 2868K bss, 125140K reserved, 0K cma-reserved) Sep 16 04:58:23.921039 kernel: devtmpfs: initialized Sep 16 04:58:23.921047 kernel: x86/mm: Memory block size: 128MB Sep 16 04:58:23.921056 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 16 04:58:23.921069 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 16 04:58:23.921078 kernel: pinctrl core: initialized pinctrl subsystem Sep 16 04:58:23.921087 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 16 04:58:23.921095 kernel: audit: initializing netlink subsys (disabled) Sep 16 04:58:23.921104 kernel: audit: type=2000 audit(1757998699.451:1): state=initialized audit_enabled=0 res=1 Sep 16 04:58:23.921114 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 16 04:58:23.921128 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 16 04:58:23.921137 kernel: cpuidle: using governor menu Sep 16 04:58:23.921145 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 16 04:58:23.921154 kernel: dca service started, version 1.12.1 Sep 16 04:58:23.921162 kernel: PCI: Using configuration type 1 for base access Sep 16 04:58:23.921171 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 16 04:58:23.921179 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 16 04:58:23.921189 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 16 04:58:23.921197 kernel: ACPI: Added _OSI(Module Device) Sep 16 04:58:23.921208 kernel: ACPI: Added _OSI(Processor Device) Sep 16 04:58:23.921217 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 16 04:58:23.921225 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 16 04:58:23.921234 kernel: ACPI: Interpreter enabled Sep 16 04:58:23.923296 kernel: ACPI: PM: (supports S0 S5) Sep 16 04:58:23.923316 kernel: ACPI: Using IOAPIC for interrupt routing Sep 16 04:58:23.923326 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 16 04:58:23.923336 kernel: PCI: Using E820 reservations for host bridge windows Sep 16 04:58:23.923345 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Sep 16 04:58:23.923363 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 16 04:58:23.923640 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Sep 16 04:58:23.923742 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Sep 16 04:58:23.923833 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Sep 16 04:58:23.923845 kernel: acpiphp: Slot [3] registered Sep 16 04:58:23.923854 kernel: acpiphp: Slot [4] registered Sep 16 04:58:23.923863 kernel: acpiphp: Slot [5] registered Sep 16 04:58:23.923876 kernel: acpiphp: Slot [6] registered Sep 16 04:58:23.923884 kernel: acpiphp: Slot [7] registered Sep 16 04:58:23.923893 kernel: acpiphp: Slot [8] registered Sep 16 04:58:23.923902 kernel: acpiphp: Slot [9] registered Sep 16 04:58:23.923910 kernel: acpiphp: Slot [10] registered Sep 16 04:58:23.923919 kernel: acpiphp: Slot [11] registered Sep 16 04:58:23.923927 kernel: acpiphp: Slot [12] registered Sep 16 04:58:23.923936 kernel: acpiphp: Slot [13] registered Sep 16 04:58:23.923944 kernel: acpiphp: Slot [14] registered Sep 16 04:58:23.923953 kernel: acpiphp: Slot [15] registered Sep 16 04:58:23.923963 kernel: acpiphp: Slot [16] registered Sep 16 04:58:23.923972 kernel: acpiphp: Slot [17] registered Sep 16 04:58:23.923980 kernel: acpiphp: Slot [18] registered Sep 16 04:58:23.923988 kernel: acpiphp: Slot [19] registered Sep 16 04:58:23.923997 kernel: acpiphp: Slot [20] registered Sep 16 04:58:23.924005 kernel: acpiphp: Slot [21] registered Sep 16 04:58:23.924013 kernel: acpiphp: Slot [22] registered Sep 16 04:58:23.924022 kernel: acpiphp: Slot [23] registered Sep 16 04:58:23.924030 kernel: acpiphp: Slot [24] registered Sep 16 04:58:23.924041 kernel: acpiphp: Slot [25] registered Sep 16 04:58:23.924049 kernel: acpiphp: Slot [26] registered Sep 16 04:58:23.924058 kernel: acpiphp: Slot [27] registered Sep 16 04:58:23.924066 kernel: acpiphp: Slot [28] registered Sep 16 04:58:23.924074 kernel: acpiphp: Slot [29] registered Sep 16 04:58:23.924083 kernel: acpiphp: Slot [30] registered Sep 16 04:58:23.924091 kernel: acpiphp: Slot [31] registered Sep 16 04:58:23.924099 kernel: PCI host bridge to bus 0000:00 Sep 16 04:58:23.924220 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 16 04:58:23.924329 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 16 04:58:23.924411 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 16 04:58:23.924492 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Sep 16 04:58:23.924581 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Sep 16 04:58:23.924661 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 16 04:58:23.924842 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint Sep 16 04:58:23.924962 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint Sep 16 04:58:23.925079 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint Sep 16 04:58:23.925173 kernel: pci 0000:00:01.1: BAR 4 [io 0xc1e0-0xc1ef] Sep 16 04:58:23.927072 kernel: pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk Sep 16 04:58:23.927213 kernel: pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk Sep 16 04:58:23.927417 kernel: pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk Sep 16 04:58:23.927528 kernel: pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk Sep 16 04:58:23.927648 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint Sep 16 04:58:23.927852 kernel: pci 0000:00:01.2: BAR 4 [io 0xc180-0xc19f] Sep 16 04:58:23.927972 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint Sep 16 04:58:23.928064 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Sep 16 04:58:23.928162 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Sep 16 04:58:23.928326 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 conventional PCI endpoint Sep 16 04:58:23.928455 kernel: pci 0000:00:02.0: BAR 0 [mem 0xfe000000-0xfe7fffff pref] Sep 16 04:58:23.928580 kernel: pci 0000:00:02.0: BAR 2 [mem 0xfe800000-0xfe803fff 64bit pref] Sep 16 04:58:23.928698 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfebf0000-0xfebf0fff] Sep 16 04:58:23.928819 kernel: pci 0000:00:02.0: ROM [mem 0xfebe0000-0xfebeffff pref] Sep 16 04:58:23.928912 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 16 04:58:23.929025 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 16 04:58:23.929128 kernel: pci 0000:00:03.0: BAR 0 [io 0xc1a0-0xc1bf] Sep 16 04:58:23.929229 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebf1000-0xfebf1fff] Sep 16 04:58:23.929343 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe804000-0xfe807fff 64bit pref] Sep 16 04:58:23.929467 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 16 04:58:23.929559 kernel: pci 0000:00:04.0: BAR 0 [io 0xc1c0-0xc1df] Sep 16 04:58:23.929654 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebf2000-0xfebf2fff] Sep 16 04:58:23.929753 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe808000-0xfe80bfff 64bit pref] Sep 16 04:58:23.929874 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 conventional PCI endpoint Sep 16 04:58:23.929976 kernel: pci 0000:00:05.0: BAR 0 [io 0xc100-0xc13f] Sep 16 04:58:23.930067 kernel: pci 0000:00:05.0: BAR 1 [mem 0xfebf3000-0xfebf3fff] Sep 16 04:58:23.930169 kernel: pci 0000:00:05.0: BAR 4 [mem 0xfe80c000-0xfe80ffff 64bit pref] Sep 16 04:58:23.930314 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 16 04:58:23.930408 kernel: pci 0000:00:06.0: BAR 0 [io 0xc000-0xc07f] Sep 16 04:58:23.930498 kernel: pci 0000:00:06.0: BAR 1 [mem 0xfebf4000-0xfebf4fff] Sep 16 04:58:23.930595 kernel: pci 0000:00:06.0: BAR 4 [mem 0xfe810000-0xfe813fff 64bit pref] Sep 16 04:58:23.930737 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 16 04:58:23.930829 kernel: pci 0000:00:07.0: BAR 0 [io 0xc080-0xc0ff] Sep 16 04:58:23.930927 kernel: pci 0000:00:07.0: BAR 1 [mem 0xfebf5000-0xfebf5fff] Sep 16 04:58:23.931021 kernel: pci 0000:00:07.0: BAR 4 [mem 0xfe814000-0xfe817fff 64bit pref] Sep 16 04:58:23.931131 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint Sep 16 04:58:23.931236 kernel: pci 0000:00:08.0: BAR 0 [io 0xc140-0xc17f] Sep 16 04:58:23.931352 kernel: pci 0000:00:08.0: BAR 4 [mem 0xfe818000-0xfe81bfff 64bit pref] Sep 16 04:58:23.931363 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 16 04:58:23.931372 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 16 04:58:23.931380 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 16 04:58:23.931390 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 16 04:58:23.931401 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Sep 16 04:58:23.931410 kernel: iommu: Default domain type: Translated Sep 16 04:58:23.931418 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 16 04:58:23.931430 kernel: PCI: Using ACPI for IRQ routing Sep 16 04:58:23.931439 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 16 04:58:23.931447 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 16 04:58:23.931457 kernel: e820: reserve RAM buffer [mem 0x7ffdb000-0x7fffffff] Sep 16 04:58:23.931596 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Sep 16 04:58:23.931725 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Sep 16 04:58:23.931818 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 16 04:58:23.931829 kernel: vgaarb: loaded Sep 16 04:58:23.931838 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 16 04:58:23.931852 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 16 04:58:23.931861 kernel: clocksource: Switched to clocksource kvm-clock Sep 16 04:58:23.931869 kernel: VFS: Disk quotas dquot_6.6.0 Sep 16 04:58:23.931878 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 16 04:58:23.931887 kernel: pnp: PnP ACPI init Sep 16 04:58:23.931896 kernel: pnp: PnP ACPI: found 4 devices Sep 16 04:58:23.931905 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 16 04:58:23.931920 kernel: NET: Registered PF_INET protocol family Sep 16 04:58:23.931929 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 16 04:58:23.931940 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Sep 16 04:58:23.931949 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 16 04:58:23.931958 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 16 04:58:23.931966 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Sep 16 04:58:23.931975 kernel: TCP: Hash tables configured (established 16384 bind 16384) Sep 16 04:58:23.931984 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Sep 16 04:58:23.931992 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Sep 16 04:58:23.932002 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 16 04:58:23.932010 kernel: NET: Registered PF_XDP protocol family Sep 16 04:58:23.932105 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 16 04:58:23.932190 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 16 04:58:23.932312 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 16 04:58:23.932399 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Sep 16 04:58:23.932481 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Sep 16 04:58:23.932580 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Sep 16 04:58:23.932677 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Sep 16 04:58:23.932690 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Sep 16 04:58:23.932818 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x720 took 26729 usecs Sep 16 04:58:23.932831 kernel: PCI: CLS 0 bytes, default 64 Sep 16 04:58:23.932840 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 16 04:58:23.932850 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x3985c314e25, max_idle_ns: 881590612270 ns Sep 16 04:58:23.932900 kernel: Initialise system trusted keyrings Sep 16 04:58:23.932909 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Sep 16 04:58:23.932918 kernel: Key type asymmetric registered Sep 16 04:58:23.932926 kernel: Asymmetric key parser 'x509' registered Sep 16 04:58:23.932941 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 16 04:58:23.932950 kernel: io scheduler mq-deadline registered Sep 16 04:58:23.932959 kernel: io scheduler kyber registered Sep 16 04:58:23.932968 kernel: io scheduler bfq registered Sep 16 04:58:23.932976 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 16 04:58:23.932986 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Sep 16 04:58:23.932994 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Sep 16 04:58:23.933003 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Sep 16 04:58:23.933012 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 16 04:58:23.933020 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 16 04:58:23.933032 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 16 04:58:23.933040 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 16 04:58:23.933049 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 16 04:58:23.933170 kernel: rtc_cmos 00:03: RTC can wake from S4 Sep 16 04:58:23.933274 kernel: rtc_cmos 00:03: registered as rtc0 Sep 16 04:58:23.933286 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 Sep 16 04:58:23.933367 kernel: rtc_cmos 00:03: setting system clock to 2025-09-16T04:58:23 UTC (1757998703) Sep 16 04:58:23.933473 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Sep 16 04:58:23.933484 kernel: intel_pstate: CPU model not supported Sep 16 04:58:23.933492 kernel: NET: Registered PF_INET6 protocol family Sep 16 04:58:23.933501 kernel: Segment Routing with IPv6 Sep 16 04:58:23.933510 kernel: In-situ OAM (IOAM) with IPv6 Sep 16 04:58:23.933519 kernel: NET: Registered PF_PACKET protocol family Sep 16 04:58:23.933527 kernel: Key type dns_resolver registered Sep 16 04:58:23.933536 kernel: IPI shorthand broadcast: enabled Sep 16 04:58:23.933544 kernel: sched_clock: Marking stable (3748006947, 143209884)->(4012696923, -121480092) Sep 16 04:58:23.933556 kernel: registered taskstats version 1 Sep 16 04:58:23.933564 kernel: Loading compiled-in X.509 certificates Sep 16 04:58:23.933573 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.47-flatcar: d1d5b0d56b9b23dabf19e645632ff93bf659b3bf' Sep 16 04:58:23.933582 kernel: Demotion targets for Node 0: null Sep 16 04:58:23.933590 kernel: Key type .fscrypt registered Sep 16 04:58:23.933599 kernel: Key type fscrypt-provisioning registered Sep 16 04:58:23.933629 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 16 04:58:23.933640 kernel: ima: Allocated hash algorithm: sha1 Sep 16 04:58:23.933649 kernel: ima: No architecture policies found Sep 16 04:58:23.933660 kernel: clk: Disabling unused clocks Sep 16 04:58:23.933672 kernel: Warning: unable to open an initial console. Sep 16 04:58:23.933681 kernel: Freeing unused kernel image (initmem) memory: 54096K Sep 16 04:58:23.933690 kernel: Write protecting the kernel read-only data: 24576k Sep 16 04:58:23.933699 kernel: Freeing unused kernel image (rodata/data gap) memory: 248K Sep 16 04:58:23.933708 kernel: Run /init as init process Sep 16 04:58:23.933716 kernel: with arguments: Sep 16 04:58:23.933725 kernel: /init Sep 16 04:58:23.933733 kernel: with environment: Sep 16 04:58:23.933745 kernel: HOME=/ Sep 16 04:58:23.933753 kernel: TERM=linux Sep 16 04:58:23.933762 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 16 04:58:23.933772 systemd[1]: Successfully made /usr/ read-only. Sep 16 04:58:23.933785 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 16 04:58:23.933798 systemd[1]: Detected virtualization kvm. Sep 16 04:58:23.933807 systemd[1]: Detected architecture x86-64. Sep 16 04:58:23.933818 systemd[1]: Running in initrd. Sep 16 04:58:23.933827 systemd[1]: No hostname configured, using default hostname. Sep 16 04:58:23.933836 systemd[1]: Hostname set to . Sep 16 04:58:23.933845 systemd[1]: Initializing machine ID from VM UUID. Sep 16 04:58:23.933854 systemd[1]: Queued start job for default target initrd.target. Sep 16 04:58:23.933864 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 16 04:58:23.933873 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 16 04:58:23.933883 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 16 04:58:23.933895 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 16 04:58:23.933904 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 16 04:58:23.933916 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 16 04:58:23.933927 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 16 04:58:23.933939 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 16 04:58:23.933948 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 16 04:58:23.933957 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 16 04:58:23.933967 systemd[1]: Reached target paths.target - Path Units. Sep 16 04:58:23.933976 systemd[1]: Reached target slices.target - Slice Units. Sep 16 04:58:23.933985 systemd[1]: Reached target swap.target - Swaps. Sep 16 04:58:23.933994 systemd[1]: Reached target timers.target - Timer Units. Sep 16 04:58:23.934004 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 16 04:58:23.934013 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 16 04:58:23.934032 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 16 04:58:23.934046 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 16 04:58:23.934056 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 16 04:58:23.934071 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 16 04:58:23.934080 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 16 04:58:23.934089 systemd[1]: Reached target sockets.target - Socket Units. Sep 16 04:58:23.934098 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 16 04:58:23.934107 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 16 04:58:23.934119 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 16 04:58:23.934128 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 16 04:58:23.934138 systemd[1]: Starting systemd-fsck-usr.service... Sep 16 04:58:23.934148 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 16 04:58:23.934157 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 16 04:58:23.934166 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:58:23.934176 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 16 04:58:23.934188 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 16 04:58:23.934203 systemd[1]: Finished systemd-fsck-usr.service. Sep 16 04:58:23.934213 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 16 04:58:23.934280 systemd-journald[211]: Collecting audit messages is disabled. Sep 16 04:58:23.934310 systemd-journald[211]: Journal started Sep 16 04:58:23.934332 systemd-journald[211]: Runtime Journal (/run/log/journal/9b0569b0d67a47ba8381fba8b5da78f5) is 4.9M, max 39.5M, 34.6M free. Sep 16 04:58:23.900908 systemd-modules-load[213]: Inserted module 'overlay' Sep 16 04:58:23.946289 systemd[1]: Started systemd-journald.service - Journal Service. Sep 16 04:58:23.949159 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 16 04:58:24.007639 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 16 04:58:24.007682 kernel: Bridge firewalling registered Sep 16 04:58:23.953161 systemd-modules-load[213]: Inserted module 'br_netfilter' Sep 16 04:58:24.009851 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 16 04:58:24.013430 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:24.014571 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 16 04:58:24.020424 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 16 04:58:24.024516 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 16 04:58:24.025111 systemd-tmpfiles[226]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 16 04:58:24.030989 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 16 04:58:24.038541 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 16 04:58:24.056931 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 16 04:58:24.061364 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 16 04:58:24.066456 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 16 04:58:24.068640 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 16 04:58:24.070963 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 16 04:58:24.105275 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=0b876f86a632750e9937176808a48c2452d5168964273bcfc3c72f2a26140c06 Sep 16 04:58:24.114884 systemd-resolved[247]: Positive Trust Anchors: Sep 16 04:58:24.114906 systemd-resolved[247]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 16 04:58:24.114950 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 16 04:58:24.117807 systemd-resolved[247]: Defaulting to hostname 'linux'. Sep 16 04:58:24.119889 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 16 04:58:24.123254 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 16 04:58:24.239299 kernel: SCSI subsystem initialized Sep 16 04:58:24.255290 kernel: Loading iSCSI transport class v2.0-870. Sep 16 04:58:24.272297 kernel: iscsi: registered transport (tcp) Sep 16 04:58:24.305421 kernel: iscsi: registered transport (qla4xxx) Sep 16 04:58:24.305497 kernel: QLogic iSCSI HBA Driver Sep 16 04:58:24.331889 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 16 04:58:24.354981 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 16 04:58:24.357919 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 16 04:58:24.413736 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 16 04:58:24.416064 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 16 04:58:24.482304 kernel: raid6: avx2x4 gen() 18140 MB/s Sep 16 04:58:24.500316 kernel: raid6: avx2x2 gen() 17854 MB/s Sep 16 04:58:24.517537 kernel: raid6: avx2x1 gen() 14922 MB/s Sep 16 04:58:24.517642 kernel: raid6: using algorithm avx2x4 gen() 18140 MB/s Sep 16 04:58:24.536332 kernel: raid6: .... xor() 9083 MB/s, rmw enabled Sep 16 04:58:24.536436 kernel: raid6: using avx2x2 recovery algorithm Sep 16 04:58:24.562305 kernel: xor: automatically using best checksumming function avx Sep 16 04:58:24.755299 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 16 04:58:24.764498 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 16 04:58:24.768032 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 16 04:58:24.805402 systemd-udevd[459]: Using default interface naming scheme 'v255'. Sep 16 04:58:24.811435 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 16 04:58:24.816141 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 16 04:58:24.840094 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation Sep 16 04:58:24.873540 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 16 04:58:24.876932 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 16 04:58:24.942425 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 16 04:58:24.944606 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 16 04:58:25.045294 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues Sep 16 04:58:25.055552 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Sep 16 04:58:25.059294 kernel: virtio_scsi virtio3: 2/0/0 default/read/poll queues Sep 16 04:58:25.063293 kernel: cryptd: max_cpu_qlen set to 1000 Sep 16 04:58:25.074280 kernel: scsi host0: Virtio SCSI HBA Sep 16 04:58:25.079919 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 16 04:58:25.079983 kernel: GPT:9289727 != 125829119 Sep 16 04:58:25.080006 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 16 04:58:25.081576 kernel: GPT:9289727 != 125829119 Sep 16 04:58:25.081632 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 16 04:58:25.082547 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 16 04:58:25.099291 kernel: ACPI: bus type USB registered Sep 16 04:58:25.102870 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues Sep 16 04:58:25.103125 kernel: AES CTR mode by8 optimization enabled Sep 16 04:58:25.109285 kernel: usbcore: registered new interface driver usbfs Sep 16 04:58:25.114656 kernel: virtio_blk virtio5: [vdb] 932 512-byte logical blocks (477 kB/466 KiB) Sep 16 04:58:25.124535 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:58:25.124743 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:25.127352 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:58:25.131577 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:58:25.141488 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 16 04:58:25.141540 kernel: usbcore: registered new interface driver hub Sep 16 04:58:25.140172 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 16 04:58:25.160103 kernel: usbcore: registered new device driver usb Sep 16 04:58:25.210273 kernel: libata version 3.00 loaded. Sep 16 04:58:25.220402 kernel: ata_piix 0000:00:01.1: version 2.13 Sep 16 04:58:25.224276 kernel: scsi host1: ata_piix Sep 16 04:58:25.227275 kernel: scsi host2: ata_piix Sep 16 04:58:25.227530 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 lpm-pol 0 Sep 16 04:58:25.227552 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 lpm-pol 0 Sep 16 04:58:25.253716 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 16 04:58:25.282697 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 16 04:58:25.283931 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:25.303844 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 16 04:58:25.326691 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 16 04:58:25.341007 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 16 04:58:25.343357 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 16 04:58:25.380144 disk-uuid[611]: Primary Header is updated. Sep 16 04:58:25.380144 disk-uuid[611]: Secondary Entries is updated. Sep 16 04:58:25.380144 disk-uuid[611]: Secondary Header is updated. Sep 16 04:58:25.386350 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 16 04:58:25.394282 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 16 04:58:25.422964 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Sep 16 04:58:25.423266 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Sep 16 04:58:25.426074 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Sep 16 04:58:25.426341 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 Sep 16 04:58:25.434839 kernel: hub 1-0:1.0: USB hub found Sep 16 04:58:25.435152 kernel: hub 1-0:1.0: 2 ports detected Sep 16 04:58:25.547480 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 16 04:58:25.583461 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 16 04:58:25.585046 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 16 04:58:25.586433 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 16 04:58:25.588949 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 16 04:58:25.624034 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 16 04:58:26.399649 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 16 04:58:26.401121 disk-uuid[612]: The operation has completed successfully. Sep 16 04:58:26.456567 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 16 04:58:26.457396 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 16 04:58:26.511435 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 16 04:58:26.537402 sh[636]: Success Sep 16 04:58:26.566650 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 16 04:58:26.566746 kernel: device-mapper: uevent: version 1.0.3 Sep 16 04:58:26.568315 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 16 04:58:26.585333 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 16 04:58:26.633103 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 16 04:58:26.637369 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 16 04:58:26.651525 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 16 04:58:26.662538 kernel: BTRFS: device fsid f1b91845-3914-4d21-a370-6d760ee45b2e devid 1 transid 36 /dev/mapper/usr (253:0) scanned by mount (648) Sep 16 04:58:26.662623 kernel: BTRFS info (device dm-0): first mount of filesystem f1b91845-3914-4d21-a370-6d760ee45b2e Sep 16 04:58:26.665870 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:58:26.673699 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 16 04:58:26.673785 kernel: BTRFS info (device dm-0): enabling free space tree Sep 16 04:58:26.676462 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 16 04:58:26.677203 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 16 04:58:26.678218 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 16 04:58:26.679149 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 16 04:58:26.683426 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 16 04:58:26.726332 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (681) Sep 16 04:58:26.730099 kernel: BTRFS info (device vda6): first mount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:58:26.730158 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:58:26.736106 kernel: BTRFS info (device vda6): turning on async discard Sep 16 04:58:26.736181 kernel: BTRFS info (device vda6): enabling free space tree Sep 16 04:58:26.744315 kernel: BTRFS info (device vda6): last unmount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:58:26.745560 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 16 04:58:26.748363 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 16 04:58:26.853405 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 16 04:58:26.856691 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 16 04:58:26.902754 systemd-networkd[817]: lo: Link UP Sep 16 04:58:26.902783 systemd-networkd[817]: lo: Gained carrier Sep 16 04:58:26.905830 systemd-networkd[817]: Enumeration completed Sep 16 04:58:26.906233 systemd-networkd[817]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Sep 16 04:58:26.906237 systemd-networkd[817]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. Sep 16 04:58:26.906418 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 16 04:58:26.907113 systemd-networkd[817]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 16 04:58:26.907117 systemd-networkd[817]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 16 04:58:26.907686 systemd-networkd[817]: eth0: Link UP Sep 16 04:58:26.907851 systemd-networkd[817]: eth1: Link UP Sep 16 04:58:26.908031 systemd-networkd[817]: eth0: Gained carrier Sep 16 04:58:26.908041 systemd-networkd[817]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Sep 16 04:58:26.911227 systemd[1]: Reached target network.target - Network. Sep 16 04:58:26.917469 systemd-networkd[817]: eth1: Gained carrier Sep 16 04:58:26.917487 systemd-networkd[817]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 16 04:58:26.929384 systemd-networkd[817]: eth0: DHCPv4 address 64.23.250.0/20, gateway 64.23.240.1 acquired from 169.254.169.253 Sep 16 04:58:26.947366 systemd-networkd[817]: eth1: DHCPv4 address 10.124.0.34/20 acquired from 169.254.169.253 Sep 16 04:58:26.969604 ignition[726]: Ignition 2.22.0 Sep 16 04:58:26.969619 ignition[726]: Stage: fetch-offline Sep 16 04:58:26.969652 ignition[726]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:58:26.969661 ignition[726]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 16 04:58:26.969746 ignition[726]: parsed url from cmdline: "" Sep 16 04:58:26.972898 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 16 04:58:26.969749 ignition[726]: no config URL provided Sep 16 04:58:26.969754 ignition[726]: reading system config file "/usr/lib/ignition/user.ign" Sep 16 04:58:26.969761 ignition[726]: no config at "/usr/lib/ignition/user.ign" Sep 16 04:58:26.969767 ignition[726]: failed to fetch config: resource requires networking Sep 16 04:58:26.971576 ignition[726]: Ignition finished successfully Sep 16 04:58:26.976426 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 16 04:58:27.010729 ignition[827]: Ignition 2.22.0 Sep 16 04:58:27.010745 ignition[827]: Stage: fetch Sep 16 04:58:27.010936 ignition[827]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:58:27.010949 ignition[827]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 16 04:58:27.011103 ignition[827]: parsed url from cmdline: "" Sep 16 04:58:27.011108 ignition[827]: no config URL provided Sep 16 04:58:27.011116 ignition[827]: reading system config file "/usr/lib/ignition/user.ign" Sep 16 04:58:27.011126 ignition[827]: no config at "/usr/lib/ignition/user.ign" Sep 16 04:58:27.011163 ignition[827]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 Sep 16 04:58:27.026575 ignition[827]: GET result: OK Sep 16 04:58:27.029199 ignition[827]: parsing config with SHA512: 78e6e268a1ae0b38d66c13d3d9fafc01fb64b75c6aa95697a9a66a2a9c81438d5e0cd55c6d13b354246722f0ee69b7a5dd45a3c3d427c94e2c8d16f28023d708 Sep 16 04:58:27.032640 unknown[827]: fetched base config from "system" Sep 16 04:58:27.032655 unknown[827]: fetched base config from "system" Sep 16 04:58:27.033295 ignition[827]: fetch: fetch complete Sep 16 04:58:27.032661 unknown[827]: fetched user config from "digitalocean" Sep 16 04:58:27.033305 ignition[827]: fetch: fetch passed Sep 16 04:58:27.033361 ignition[827]: Ignition finished successfully Sep 16 04:58:27.037503 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 16 04:58:27.041407 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 16 04:58:27.078491 ignition[834]: Ignition 2.22.0 Sep 16 04:58:27.079302 ignition[834]: Stage: kargs Sep 16 04:58:27.079500 ignition[834]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:58:27.079512 ignition[834]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 16 04:58:27.082989 ignition[834]: kargs: kargs passed Sep 16 04:58:27.083064 ignition[834]: Ignition finished successfully Sep 16 04:58:27.086179 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 16 04:58:27.088095 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 16 04:58:27.129305 ignition[840]: Ignition 2.22.0 Sep 16 04:58:27.129321 ignition[840]: Stage: disks Sep 16 04:58:27.129499 ignition[840]: no configs at "/usr/lib/ignition/base.d" Sep 16 04:58:27.129510 ignition[840]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 16 04:58:27.131721 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 16 04:58:27.130209 ignition[840]: disks: disks passed Sep 16 04:58:27.133486 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 16 04:58:27.130295 ignition[840]: Ignition finished successfully Sep 16 04:58:27.134405 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 16 04:58:27.135514 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 16 04:58:27.136985 systemd[1]: Reached target sysinit.target - System Initialization. Sep 16 04:58:27.138195 systemd[1]: Reached target basic.target - Basic System. Sep 16 04:58:27.140991 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 16 04:58:27.175944 systemd-fsck[848]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 16 04:58:27.179480 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 16 04:58:27.182776 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 16 04:58:27.310321 kernel: EXT4-fs (vda9): mounted filesystem fb1cb44f-955b-4cd0-8849-33ce3640d547 r/w with ordered data mode. Quota mode: none. Sep 16 04:58:27.311031 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 16 04:58:27.312093 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 16 04:58:27.314227 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 16 04:58:27.317363 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 16 04:58:27.318650 systemd[1]: Starting flatcar-afterburn-network.service - Flatcar Afterburn network service... Sep 16 04:58:27.327609 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 16 04:58:27.329196 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 16 04:58:27.331372 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 16 04:58:27.336556 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 16 04:58:27.343314 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (856) Sep 16 04:58:27.347981 kernel: BTRFS info (device vda6): first mount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:58:27.348043 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:58:27.349805 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 16 04:58:27.357475 kernel: BTRFS info (device vda6): turning on async discard Sep 16 04:58:27.357550 kernel: BTRFS info (device vda6): enabling free space tree Sep 16 04:58:27.363783 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 16 04:58:27.420276 coreos-metadata[858]: Sep 16 04:58:27.418 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 16 04:58:27.427349 initrd-setup-root[886]: cut: /sysroot/etc/passwd: No such file or directory Sep 16 04:58:27.432476 coreos-metadata[858]: Sep 16 04:58:27.432 INFO Fetch successful Sep 16 04:58:27.440394 coreos-metadata[859]: Sep 16 04:58:27.439 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 16 04:58:27.441995 systemd[1]: flatcar-afterburn-network.service: Deactivated successfully. Sep 16 04:58:27.445418 initrd-setup-root[893]: cut: /sysroot/etc/group: No such file or directory Sep 16 04:58:27.442121 systemd[1]: Finished flatcar-afterburn-network.service - Flatcar Afterburn network service. Sep 16 04:58:27.451959 initrd-setup-root[901]: cut: /sysroot/etc/shadow: No such file or directory Sep 16 04:58:27.453583 coreos-metadata[859]: Sep 16 04:58:27.453 INFO Fetch successful Sep 16 04:58:27.459669 coreos-metadata[859]: Sep 16 04:58:27.459 INFO wrote hostname ci-4459.0.0-n-96e83010f3 to /sysroot/etc/hostname Sep 16 04:58:27.461542 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 16 04:58:27.463135 initrd-setup-root[908]: cut: /sysroot/etc/gshadow: No such file or directory Sep 16 04:58:27.583844 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 16 04:58:27.586578 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 16 04:58:27.589433 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 16 04:58:27.619349 kernel: BTRFS info (device vda6): last unmount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:58:27.637933 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 16 04:58:27.663146 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 16 04:58:27.672983 ignition[977]: INFO : Ignition 2.22.0 Sep 16 04:58:27.672983 ignition[977]: INFO : Stage: mount Sep 16 04:58:27.674580 ignition[977]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 16 04:58:27.674580 ignition[977]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 16 04:58:27.676691 ignition[977]: INFO : mount: mount passed Sep 16 04:58:27.677553 ignition[977]: INFO : Ignition finished successfully Sep 16 04:58:27.680192 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 16 04:58:27.682407 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 16 04:58:27.711404 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 16 04:58:27.751311 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (989) Sep 16 04:58:27.751440 kernel: BTRFS info (device vda6): first mount of filesystem 8b047ef5-4757-404a-b211-2a505a425364 Sep 16 04:58:27.755436 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 16 04:58:27.760665 kernel: BTRFS info (device vda6): turning on async discard Sep 16 04:58:27.760885 kernel: BTRFS info (device vda6): enabling free space tree Sep 16 04:58:27.765071 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 16 04:58:27.810850 ignition[1006]: INFO : Ignition 2.22.0 Sep 16 04:58:27.813167 ignition[1006]: INFO : Stage: files Sep 16 04:58:27.813167 ignition[1006]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 16 04:58:27.813167 ignition[1006]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 16 04:58:27.815200 ignition[1006]: DEBUG : files: compiled without relabeling support, skipping Sep 16 04:58:27.815200 ignition[1006]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 16 04:58:27.815200 ignition[1006]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 16 04:58:27.818198 ignition[1006]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 16 04:58:27.819021 ignition[1006]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 16 04:58:27.819021 ignition[1006]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 16 04:58:27.818760 unknown[1006]: wrote ssh authorized keys file for user: core Sep 16 04:58:27.821490 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 16 04:58:27.821490 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 16 04:58:27.824092 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 16 04:58:27.825407 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 16 04:58:27.825407 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Sep 16 04:58:27.827646 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Sep 16 04:58:27.827646 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Sep 16 04:58:27.827646 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-x86-64.raw: attempt #1 Sep 16 04:58:28.149567 systemd-networkd[817]: eth1: Gained IPv6LL Sep 16 04:58:28.225389 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 16 04:58:28.853412 systemd-networkd[817]: eth0: Gained IPv6LL Sep 16 04:58:29.327176 ignition[1006]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-x86-64.raw" Sep 16 04:58:29.328668 ignition[1006]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 16 04:58:29.328668 ignition[1006]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 16 04:58:29.328668 ignition[1006]: INFO : files: files passed Sep 16 04:58:29.328668 ignition[1006]: INFO : Ignition finished successfully Sep 16 04:58:29.329220 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 16 04:58:29.332417 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 16 04:58:29.336413 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 16 04:58:29.350713 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 16 04:58:29.350858 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 16 04:58:29.359273 initrd-setup-root-after-ignition[1036]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 16 04:58:29.359273 initrd-setup-root-after-ignition[1036]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 16 04:58:29.362552 initrd-setup-root-after-ignition[1040]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 16 04:58:29.365106 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 16 04:58:29.366710 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 16 04:58:29.369447 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 16 04:58:29.425130 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 16 04:58:29.425303 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 16 04:58:29.426649 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 16 04:58:29.427655 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 16 04:58:29.428833 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 16 04:58:29.430088 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 16 04:58:29.469093 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 16 04:58:29.471717 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 16 04:58:29.503180 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 16 04:58:29.503989 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 16 04:58:29.505438 systemd[1]: Stopped target timers.target - Timer Units. Sep 16 04:58:29.506683 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 16 04:58:29.506861 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 16 04:58:29.508385 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 16 04:58:29.509083 systemd[1]: Stopped target basic.target - Basic System. Sep 16 04:58:29.510152 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 16 04:58:29.511155 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 16 04:58:29.512344 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 16 04:58:29.513635 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 16 04:58:29.514802 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 16 04:58:29.515852 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 16 04:58:29.517332 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 16 04:58:29.518758 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 16 04:58:29.520132 systemd[1]: Stopped target swap.target - Swaps. Sep 16 04:58:29.521459 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 16 04:58:29.521694 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 16 04:58:29.523221 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 16 04:58:29.524575 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 16 04:58:29.526036 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 16 04:58:29.526163 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 16 04:58:29.527299 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 16 04:58:29.527534 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 16 04:58:29.528877 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 16 04:58:29.529038 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 16 04:58:29.530432 systemd[1]: ignition-files.service: Deactivated successfully. Sep 16 04:58:29.530577 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 16 04:58:29.531339 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 16 04:58:29.531434 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 16 04:58:29.534369 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 16 04:58:29.535406 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 16 04:58:29.535603 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 16 04:58:29.539380 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 16 04:58:29.543446 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 16 04:58:29.543620 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 16 04:58:29.546178 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 16 04:58:29.546367 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 16 04:58:29.553504 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 16 04:58:29.554315 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 16 04:58:29.567207 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 16 04:58:29.573615 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 16 04:58:29.574339 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 16 04:58:29.585356 ignition[1060]: INFO : Ignition 2.22.0 Sep 16 04:58:29.585356 ignition[1060]: INFO : Stage: umount Sep 16 04:58:29.586786 ignition[1060]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 16 04:58:29.586786 ignition[1060]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Sep 16 04:58:29.588289 ignition[1060]: INFO : umount: umount passed Sep 16 04:58:29.588289 ignition[1060]: INFO : Ignition finished successfully Sep 16 04:58:29.589150 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 16 04:58:29.589864 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 16 04:58:29.591915 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 16 04:58:29.592010 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 16 04:58:29.594041 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 16 04:58:29.594105 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 16 04:58:29.595178 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 16 04:58:29.595228 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 16 04:58:29.596220 systemd[1]: Stopped target network.target - Network. Sep 16 04:58:29.597195 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 16 04:58:29.597269 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 16 04:58:29.602565 systemd[1]: Stopped target paths.target - Path Units. Sep 16 04:58:29.603491 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 16 04:58:29.607359 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 16 04:58:29.608729 systemd[1]: Stopped target slices.target - Slice Units. Sep 16 04:58:29.609903 systemd[1]: Stopped target sockets.target - Socket Units. Sep 16 04:58:29.610450 systemd[1]: iscsid.socket: Deactivated successfully. Sep 16 04:58:29.610498 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 16 04:58:29.611516 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 16 04:58:29.611570 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 16 04:58:29.612555 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 16 04:58:29.612634 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 16 04:58:29.613522 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 16 04:58:29.613569 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 16 04:58:29.614511 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 16 04:58:29.614562 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 16 04:58:29.615716 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 16 04:58:29.616655 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 16 04:58:29.621028 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 16 04:58:29.621182 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 16 04:58:29.625188 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 16 04:58:29.625532 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 16 04:58:29.625586 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 16 04:58:29.628550 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 16 04:58:29.628877 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 16 04:58:29.628996 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 16 04:58:29.631587 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 16 04:58:29.632057 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 16 04:58:29.633438 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 16 04:58:29.633476 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 16 04:58:29.635548 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 16 04:58:29.636593 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 16 04:58:29.636653 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 16 04:58:29.640629 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 16 04:58:29.640699 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 16 04:58:29.641446 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 16 04:58:29.641497 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 16 04:58:29.642048 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 16 04:58:29.646783 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 16 04:58:29.657909 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 16 04:58:29.660718 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 16 04:58:29.661759 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 16 04:58:29.661800 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 16 04:58:29.663067 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 16 04:58:29.663110 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 16 04:58:29.664045 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 16 04:58:29.664092 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 16 04:58:29.665600 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 16 04:58:29.665641 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 16 04:58:29.666699 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 16 04:58:29.666759 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 16 04:58:29.668588 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 16 04:58:29.670608 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 16 04:58:29.670696 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 16 04:58:29.674696 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 16 04:58:29.674753 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 16 04:58:29.676314 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:58:29.676363 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:29.679392 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 16 04:58:29.679560 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 16 04:58:29.688846 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 16 04:58:29.689004 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 16 04:58:29.690978 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 16 04:58:29.692939 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 16 04:58:29.712686 systemd[1]: Switching root. Sep 16 04:58:29.751622 systemd-journald[211]: Journal stopped Sep 16 04:58:31.245051 systemd-journald[211]: Received SIGTERM from PID 1 (systemd). Sep 16 04:58:31.245160 kernel: SELinux: policy capability network_peer_controls=1 Sep 16 04:58:31.245177 kernel: SELinux: policy capability open_perms=1 Sep 16 04:58:31.245189 kernel: SELinux: policy capability extended_socket_class=1 Sep 16 04:58:31.245205 kernel: SELinux: policy capability always_check_network=0 Sep 16 04:58:31.245216 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 16 04:58:31.245231 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 16 04:58:31.245242 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 16 04:58:31.247052 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 16 04:58:31.247071 kernel: SELinux: policy capability userspace_initial_context=0 Sep 16 04:58:31.247089 kernel: audit: type=1403 audit(1757998710.020:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 16 04:58:31.247105 systemd[1]: Successfully loaded SELinux policy in 76.798ms. Sep 16 04:58:31.247134 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.031ms. Sep 16 04:58:31.247148 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 16 04:58:31.247162 systemd[1]: Detected virtualization kvm. Sep 16 04:58:31.247178 systemd[1]: Detected architecture x86-64. Sep 16 04:58:31.247192 systemd[1]: Detected first boot. Sep 16 04:58:31.247206 systemd[1]: Hostname set to . Sep 16 04:58:31.247217 systemd[1]: Initializing machine ID from VM UUID. Sep 16 04:58:31.247229 zram_generator::config[1105]: No configuration found. Sep 16 04:58:31.249872 kernel: Guest personality initialized and is inactive Sep 16 04:58:31.249917 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 16 04:58:31.249956 kernel: Initialized host personality Sep 16 04:58:31.249967 kernel: NET: Registered PF_VSOCK protocol family Sep 16 04:58:31.249980 systemd[1]: Populated /etc with preset unit settings. Sep 16 04:58:31.250002 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 16 04:58:31.250013 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 16 04:58:31.250026 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 16 04:58:31.250037 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 16 04:58:31.250050 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 16 04:58:31.250061 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 16 04:58:31.250079 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 16 04:58:31.250091 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 16 04:58:31.250105 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 16 04:58:31.250118 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 16 04:58:31.250130 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 16 04:58:31.250151 systemd[1]: Created slice user.slice - User and Session Slice. Sep 16 04:58:31.250163 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 16 04:58:31.250175 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 16 04:58:31.250186 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 16 04:58:31.250201 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 16 04:58:31.250214 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 16 04:58:31.250227 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 16 04:58:31.250239 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 16 04:58:31.250870 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 16 04:58:31.250892 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 16 04:58:31.250904 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 16 04:58:31.250916 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 16 04:58:31.250933 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 16 04:58:31.250945 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 16 04:58:31.250962 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 16 04:58:31.250976 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 16 04:58:31.250989 systemd[1]: Reached target slices.target - Slice Units. Sep 16 04:58:31.251001 systemd[1]: Reached target swap.target - Swaps. Sep 16 04:58:31.251012 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 16 04:58:31.251023 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 16 04:58:31.251036 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 16 04:58:31.251057 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 16 04:58:31.251069 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 16 04:58:31.251080 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 16 04:58:31.251096 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 16 04:58:31.251111 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 16 04:58:31.251123 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 16 04:58:31.251135 systemd[1]: Mounting media.mount - External Media Directory... Sep 16 04:58:31.251146 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:31.251158 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 16 04:58:31.251173 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 16 04:58:31.251185 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 16 04:58:31.251205 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 16 04:58:31.251225 systemd[1]: Reached target machines.target - Containers. Sep 16 04:58:31.251237 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 16 04:58:31.251263 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:58:31.251276 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 16 04:58:31.251288 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 16 04:58:31.251300 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 16 04:58:31.251314 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 16 04:58:31.251327 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 16 04:58:31.251338 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 16 04:58:31.251350 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 16 04:58:31.251363 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 16 04:58:31.251375 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 16 04:58:31.251389 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 16 04:58:31.251402 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 16 04:58:31.251416 systemd[1]: Stopped systemd-fsck-usr.service. Sep 16 04:58:31.251429 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 16 04:58:31.251441 kernel: loop: module loaded Sep 16 04:58:31.251453 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 16 04:58:31.251465 kernel: fuse: init (API version 7.41) Sep 16 04:58:31.251476 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 16 04:58:31.251488 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 16 04:58:31.251500 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 16 04:58:31.251512 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 16 04:58:31.251527 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 16 04:58:31.251541 systemd[1]: verity-setup.service: Deactivated successfully. Sep 16 04:58:31.251553 systemd[1]: Stopped verity-setup.service. Sep 16 04:58:31.251565 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:31.251577 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 16 04:58:31.251588 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 16 04:58:31.251600 systemd[1]: Mounted media.mount - External Media Directory. Sep 16 04:58:31.251611 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 16 04:58:31.251624 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 16 04:58:31.251637 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 16 04:58:31.251651 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 16 04:58:31.251662 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 16 04:58:31.251681 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 16 04:58:31.251701 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 16 04:58:31.251715 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 16 04:58:31.251727 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 16 04:58:31.251739 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 16 04:58:31.251754 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 16 04:58:31.251765 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 16 04:58:31.251780 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 16 04:58:31.251791 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 16 04:58:31.251804 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 16 04:58:31.251816 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 16 04:58:31.251828 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 16 04:58:31.251839 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 16 04:58:31.251850 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 16 04:58:31.251865 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 16 04:58:31.251877 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 16 04:58:31.251939 systemd-journald[1175]: Collecting audit messages is disabled. Sep 16 04:58:31.251967 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 16 04:58:31.251979 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 16 04:58:31.251992 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 16 04:58:31.252004 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 16 04:58:31.252016 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 16 04:58:31.252028 systemd-journald[1175]: Journal started Sep 16 04:58:31.252056 systemd-journald[1175]: Runtime Journal (/run/log/journal/9b0569b0d67a47ba8381fba8b5da78f5) is 4.9M, max 39.5M, 34.6M free. Sep 16 04:58:30.772010 systemd[1]: Queued start job for default target multi-user.target. Sep 16 04:58:30.784564 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 16 04:58:30.785318 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 16 04:58:31.262799 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 16 04:58:31.262891 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:58:31.286755 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 16 04:58:31.286857 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 16 04:58:31.286876 kernel: ACPI: bus type drm_connector registered Sep 16 04:58:31.295128 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 16 04:58:31.300338 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 16 04:58:31.304765 systemd[1]: Started systemd-journald.service - Journal Service. Sep 16 04:58:31.307569 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 16 04:58:31.308528 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 16 04:58:31.309171 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 16 04:58:31.310098 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 16 04:58:31.311656 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 16 04:58:31.345471 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 16 04:58:31.353053 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 16 04:58:31.360279 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 16 04:58:31.361609 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 16 04:58:31.368261 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 16 04:58:31.374571 kernel: loop0: detected capacity change from 0 to 110984 Sep 16 04:58:31.401022 systemd-journald[1175]: Time spent on flushing to /var/log/journal/9b0569b0d67a47ba8381fba8b5da78f5 is 43.228ms for 997 entries. Sep 16 04:58:31.401022 systemd-journald[1175]: System Journal (/var/log/journal/9b0569b0d67a47ba8381fba8b5da78f5) is 8M, max 195.6M, 187.6M free. Sep 16 04:58:31.477610 systemd-journald[1175]: Received client request to flush runtime journal. Sep 16 04:58:31.477680 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 16 04:58:31.478515 kernel: loop1: detected capacity change from 0 to 8 Sep 16 04:58:31.414763 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 16 04:58:31.425333 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 16 04:58:31.440845 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 16 04:58:31.483838 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 16 04:58:31.485257 systemd-tmpfiles[1242]: ACLs are not supported, ignoring. Sep 16 04:58:31.485587 systemd-tmpfiles[1242]: ACLs are not supported, ignoring. Sep 16 04:58:31.491093 kernel: loop2: detected capacity change from 0 to 224512 Sep 16 04:58:31.491960 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 16 04:58:31.530306 kernel: loop3: detected capacity change from 0 to 128016 Sep 16 04:58:31.576273 kernel: loop4: detected capacity change from 0 to 110984 Sep 16 04:58:31.599285 kernel: loop5: detected capacity change from 0 to 8 Sep 16 04:58:31.606288 kernel: loop6: detected capacity change from 0 to 224512 Sep 16 04:58:31.650282 kernel: loop7: detected capacity change from 0 to 128016 Sep 16 04:58:31.679303 (sd-merge)[1253]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. Sep 16 04:58:31.681389 (sd-merge)[1253]: Merged extensions into '/usr'. Sep 16 04:58:31.692530 systemd[1]: Reload requested from client PID 1212 ('systemd-sysext') (unit systemd-sysext.service)... Sep 16 04:58:31.692556 systemd[1]: Reloading... Sep 16 04:58:31.844295 zram_generator::config[1278]: No configuration found. Sep 16 04:58:32.071051 ldconfig[1208]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 16 04:58:32.122132 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 16 04:58:32.122967 systemd[1]: Reloading finished in 429 ms. Sep 16 04:58:32.141460 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 16 04:58:32.142878 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 16 04:58:32.150358 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 16 04:58:32.160409 systemd[1]: Starting ensure-sysext.service... Sep 16 04:58:32.164142 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 16 04:58:32.180030 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 16 04:58:32.220431 systemd[1]: Reload requested from client PID 1323 ('systemctl') (unit ensure-sysext.service)... Sep 16 04:58:32.220467 systemd[1]: Reloading... Sep 16 04:58:32.244796 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 16 04:58:32.245351 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 16 04:58:32.245883 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 16 04:58:32.247437 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 16 04:58:32.252305 systemd-tmpfiles[1324]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 16 04:58:32.252929 systemd-tmpfiles[1324]: ACLs are not supported, ignoring. Sep 16 04:58:32.253119 systemd-tmpfiles[1324]: ACLs are not supported, ignoring. Sep 16 04:58:32.260654 systemd-tmpfiles[1324]: Detected autofs mount point /boot during canonicalization of boot. Sep 16 04:58:32.260669 systemd-tmpfiles[1324]: Skipping /boot Sep 16 04:58:32.286453 systemd-tmpfiles[1324]: Detected autofs mount point /boot during canonicalization of boot. Sep 16 04:58:32.286468 systemd-tmpfiles[1324]: Skipping /boot Sep 16 04:58:32.339315 zram_generator::config[1348]: No configuration found. Sep 16 04:58:32.563857 systemd[1]: Reloading finished in 342 ms. Sep 16 04:58:32.586953 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 16 04:58:32.588420 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 16 04:58:32.603476 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 16 04:58:32.608455 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 16 04:58:32.613901 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 16 04:58:32.619435 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 16 04:58:32.628031 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 16 04:58:32.631548 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 16 04:58:32.640388 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:32.640614 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:58:32.642451 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 16 04:58:32.652204 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 16 04:58:32.658480 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 16 04:58:32.659290 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:58:32.659422 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 16 04:58:32.659533 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:32.664544 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 16 04:58:32.668575 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:32.668893 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:58:32.669121 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:58:32.669209 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 16 04:58:32.669319 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:32.673971 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:32.674228 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:58:32.680630 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 16 04:58:32.682476 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:58:32.682630 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 16 04:58:32.682778 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:32.689329 systemd[1]: Finished ensure-sysext.service. Sep 16 04:58:32.697593 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 16 04:58:32.699944 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 16 04:58:32.701167 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 16 04:58:32.715816 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 16 04:58:32.718096 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 16 04:58:32.719832 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 16 04:58:32.721053 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 16 04:58:32.722100 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 16 04:58:32.733613 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 16 04:58:32.735934 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 16 04:58:32.736136 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 16 04:58:32.737655 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 16 04:58:32.737859 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 16 04:58:32.742432 systemd-udevd[1402]: Using default interface naming scheme 'v255'. Sep 16 04:58:32.746984 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 16 04:58:32.747080 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 16 04:58:32.747111 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 16 04:58:32.769781 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 16 04:58:32.777865 augenrules[1439]: No rules Sep 16 04:58:32.778956 systemd[1]: audit-rules.service: Deactivated successfully. Sep 16 04:58:32.779206 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 16 04:58:32.786858 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 16 04:58:32.793627 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 16 04:58:32.815910 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 16 04:58:32.961703 systemd[1]: Condition check resulted in dev-disk-by\x2dlabel-config\x2d2.device - /dev/disk/by-label/config-2 being skipped. Sep 16 04:58:32.963436 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... Sep 16 04:58:32.964596 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:32.964727 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 16 04:58:32.966346 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 16 04:58:32.970520 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 16 04:58:32.973820 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 16 04:58:32.974812 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 16 04:58:32.974857 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 16 04:58:32.974889 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 16 04:58:32.974906 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 16 04:58:33.007282 kernel: ISO 9660 Extensions: RRIP_1991A Sep 16 04:58:33.021949 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. Sep 16 04:58:33.023069 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 16 04:58:33.023707 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 16 04:58:33.029309 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 16 04:58:33.030694 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 16 04:58:33.031802 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 16 04:58:33.032040 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 16 04:58:33.034846 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 16 04:58:33.034929 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 16 04:58:33.046000 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 16 04:58:33.114277 kernel: mousedev: PS/2 mouse device common for all mice Sep 16 04:58:33.124225 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 16 04:58:33.126193 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 16 04:58:33.176703 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 16 04:58:33.190307 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 Sep 16 04:58:33.192702 systemd-networkd[1448]: lo: Link UP Sep 16 04:58:33.192715 systemd-networkd[1448]: lo: Gained carrier Sep 16 04:58:33.195827 systemd-networkd[1448]: Enumeration completed Sep 16 04:58:33.195973 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 16 04:58:33.198431 systemd-networkd[1448]: eth0: Configuring with /run/systemd/network/10-02:d6:11:3c:0b:1b.network. Sep 16 04:58:33.199512 systemd-networkd[1448]: eth1: Configuring with /run/systemd/network/10-ea:29:d1:c9:90:88.network. Sep 16 04:58:33.199861 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 16 04:58:33.200713 systemd-networkd[1448]: eth0: Link UP Sep 16 04:58:33.200876 systemd-networkd[1448]: eth0: Gained carrier Sep 16 04:58:33.204608 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 16 04:58:33.205515 systemd-networkd[1448]: eth1: Link UP Sep 16 04:58:33.206351 systemd-networkd[1448]: eth1: Gained carrier Sep 16 04:58:33.256280 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Sep 16 04:58:33.267695 kernel: ACPI: button: Power Button [PWRF] Sep 16 04:58:33.267795 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 16 04:58:33.284687 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 16 04:58:33.310140 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 16 04:58:33.311494 systemd[1]: Reached target time-set.target - System Time Set. Sep 16 04:58:33.324414 systemd-resolved[1400]: Positive Trust Anchors: Sep 16 04:58:33.324434 systemd-resolved[1400]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 16 04:58:33.324470 systemd-resolved[1400]: 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 16 04:58:33.331285 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Sep 16 04:58:33.331353 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Sep 16 04:58:33.334337 systemd-resolved[1400]: Using system hostname 'ci-4459.0.0-n-96e83010f3'. Sep 16 04:58:33.337494 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 16 04:58:33.340610 kernel: Console: switching to colour dummy device 80x25 Sep 16 04:58:33.342716 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Sep 16 04:58:33.342779 kernel: [drm] features: -context_init Sep 16 04:58:33.341805 systemd[1]: Reached target network.target - Network. Sep 16 04:58:33.341892 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 16 04:58:33.341953 systemd[1]: Reached target sysinit.target - System Initialization. Sep 16 04:58:33.342109 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 16 04:58:33.342194 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 16 04:58:33.342274 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 16 04:58:33.342486 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 16 04:58:33.342673 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 16 04:58:33.343303 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 16 04:58:33.343379 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 16 04:58:33.343407 systemd[1]: Reached target paths.target - Path Units. Sep 16 04:58:33.343455 systemd[1]: Reached target timers.target - Timer Units. Sep 16 04:58:33.344900 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 16 04:58:33.347022 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 16 04:58:33.353290 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 16 04:58:33.354556 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 16 04:58:33.355380 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 16 04:58:33.356388 kernel: [drm] number of scanouts: 1 Sep 16 04:58:33.356463 kernel: [drm] number of cap sets: 0 Sep 16 04:58:33.361299 kernel: [drm] Initialized virtio_gpu 0.1.0 for 0000:00:02.0 on minor 0 Sep 16 04:58:33.363199 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 16 04:58:33.363974 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 16 04:58:33.366659 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 16 04:58:33.367538 systemd[1]: Reached target sockets.target - Socket Units. Sep 16 04:58:33.368324 systemd[1]: Reached target basic.target - Basic System. Sep 16 04:58:33.368463 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 16 04:58:33.368488 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 16 04:58:33.372495 systemd[1]: Starting containerd.service - containerd container runtime... Sep 16 04:58:33.376556 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 16 04:58:33.379532 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 16 04:58:33.386461 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 16 04:58:33.392862 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 16 04:58:33.396478 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 16 04:58:33.396602 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 16 04:58:33.398443 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 16 04:58:33.401524 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Sep 16 04:58:33.401611 kernel: Console: switching to colour frame buffer device 128x48 Sep 16 04:58:33.412482 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 16 04:58:33.447546 coreos-metadata[1520]: Sep 16 04:58:33.447 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 16 04:58:33.460386 coreos-metadata[1520]: Sep 16 04:58:33.460 INFO Fetch successful Sep 16 04:58:33.467414 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Sep 16 04:58:33.480020 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 16 04:58:33.492559 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 16 04:58:33.504628 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 16 04:58:33.505960 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 16 04:58:33.506888 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 16 04:58:33.510989 jq[1524]: false Sep 16 04:58:33.515513 systemd[1]: Starting update-engine.service - Update Engine... Sep 16 04:58:33.521273 google_oslogin_nss_cache[1526]: oslogin_cache_refresh[1526]: Refreshing passwd entry cache Sep 16 04:58:33.518995 oslogin_cache_refresh[1526]: Refreshing passwd entry cache Sep 16 04:58:33.521845 extend-filesystems[1525]: Found /dev/vda6 Sep 16 04:58:33.525431 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 16 04:58:33.538282 google_oslogin_nss_cache[1526]: oslogin_cache_refresh[1526]: Failure getting users, quitting Sep 16 04:58:33.538282 google_oslogin_nss_cache[1526]: oslogin_cache_refresh[1526]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 16 04:58:33.538282 google_oslogin_nss_cache[1526]: oslogin_cache_refresh[1526]: Refreshing group entry cache Sep 16 04:58:33.538454 extend-filesystems[1525]: Found /dev/vda9 Sep 16 04:58:33.535623 oslogin_cache_refresh[1526]: Failure getting users, quitting Sep 16 04:58:33.535649 oslogin_cache_refresh[1526]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 16 04:58:33.535724 oslogin_cache_refresh[1526]: Refreshing group entry cache Sep 16 04:58:33.542397 oslogin_cache_refresh[1526]: Failure getting groups, quitting Sep 16 04:58:33.544404 google_oslogin_nss_cache[1526]: oslogin_cache_refresh[1526]: Failure getting groups, quitting Sep 16 04:58:33.544404 google_oslogin_nss_cache[1526]: oslogin_cache_refresh[1526]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 16 04:58:33.542412 oslogin_cache_refresh[1526]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 16 04:58:33.546472 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 16 04:58:33.554874 extend-filesystems[1525]: Checking size of /dev/vda9 Sep 16 04:58:33.548390 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 16 04:58:33.548668 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 16 04:58:33.549117 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 16 04:58:33.549802 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 16 04:58:33.551239 systemd[1]: motdgen.service: Deactivated successfully. Sep 16 04:58:33.553042 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 16 04:58:33.583327 update_engine[1543]: I20250916 04:58:33.574304 1543 main.cc:92] Flatcar Update Engine starting Sep 16 04:58:33.580818 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 16 04:58:33.581149 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 16 04:58:33.612801 extend-filesystems[1525]: Resized partition /dev/vda9 Sep 16 04:58:33.614697 extend-filesystems[1564]: resize2fs 1.47.3 (8-Jul-2025) Sep 16 04:58:33.618205 jq[1546]: true Sep 16 04:58:33.625207 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks Sep 16 04:58:33.640308 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:58:33.690434 systemd-timesyncd[1416]: Contacted time server 96.231.54.40:123 (0.flatcar.pool.ntp.org). Sep 16 04:58:33.692457 systemd-timesyncd[1416]: Initial clock synchronization to Tue 2025-09-16 04:58:33.523124 UTC. Sep 16 04:58:33.700885 (ntainerd)[1567]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 16 04:58:33.707749 jq[1566]: true Sep 16 04:58:33.746955 dbus-daemon[1522]: [system] SELinux support is enabled Sep 16 04:58:33.768493 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Sep 16 04:58:33.747294 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 16 04:58:33.759679 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 16 04:58:33.759733 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 16 04:58:33.776534 extend-filesystems[1564]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 16 04:58:33.776534 extend-filesystems[1564]: old_desc_blocks = 1, new_desc_blocks = 8 Sep 16 04:58:33.776534 extend-filesystems[1564]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Sep 16 04:58:33.761595 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 16 04:58:33.801431 extend-filesystems[1525]: Resized filesystem in /dev/vda9 Sep 16 04:58:33.761706 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 16 04:58:33.761727 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 16 04:58:33.766200 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 16 04:58:33.768872 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 16 04:58:33.773028 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 16 04:58:33.774414 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 16 04:58:33.812694 systemd[1]: Started update-engine.service - Update Engine. Sep 16 04:58:33.838150 update_engine[1543]: I20250916 04:58:33.837817 1543 update_check_scheduler.cc:74] Next update check in 3m41s Sep 16 04:58:33.843556 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 16 04:58:33.892445 bash[1593]: Updated "/home/core/.ssh/authorized_keys" Sep 16 04:58:33.897175 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 16 04:58:33.905628 systemd[1]: Starting sshkeys.service... Sep 16 04:58:33.970343 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Sep 16 04:58:33.974522 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Sep 16 04:58:34.003727 systemd-logind[1540]: Watching system buttons on /dev/input/event2 (Power Button) Sep 16 04:58:34.005487 systemd-logind[1540]: New seat seat0. Sep 16 04:58:34.006301 systemd[1]: Started systemd-logind.service - User Login Management. Sep 16 04:58:34.022972 coreos-metadata[1598]: Sep 16 04:58:34.017 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Sep 16 04:58:34.031395 coreos-metadata[1598]: Sep 16 04:58:34.029 INFO Fetch successful Sep 16 04:58:34.048406 unknown[1598]: wrote ssh authorized keys file for user: core Sep 16 04:58:34.095845 update-ssh-keys[1602]: Updated "/home/core/.ssh/authorized_keys" Sep 16 04:58:34.077745 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Sep 16 04:58:34.082524 systemd[1]: Finished sshkeys.service. Sep 16 04:58:34.096439 systemd-logind[1540]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 16 04:58:34.147922 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:34.204373 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:58:34.204577 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:34.207707 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:58:34.211148 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:58:34.215530 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 16 04:58:34.275085 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 16 04:58:34.277769 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:34.300290 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 16 04:58:34.390664 locksmithd[1583]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 16 04:58:34.391385 containerd[1567]: time="2025-09-16T04:58:34Z" 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 16 04:58:34.392620 containerd[1567]: time="2025-09-16T04:58:34.392571562Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.412914941Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="13.64µs" Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.412975797Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413001443Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413297668Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413327172Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413362689Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413444363Z" 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 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413462092Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413828057Z" 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 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413854064Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413873315Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 16 04:58:34.414285 containerd[1567]: time="2025-09-16T04:58:34.413887112Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 16 04:58:34.414749 containerd[1567]: time="2025-09-16T04:58:34.414005947Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 16 04:58:34.415012 containerd[1567]: time="2025-09-16T04:58:34.414888514Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 16 04:58:34.415012 containerd[1567]: time="2025-09-16T04:58:34.414942151Z" 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 16 04:58:34.415012 containerd[1567]: time="2025-09-16T04:58:34.414958949Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 16 04:58:34.416046 containerd[1567]: time="2025-09-16T04:58:34.415161455Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 16 04:58:34.416046 containerd[1567]: time="2025-09-16T04:58:34.415725304Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 16 04:58:34.416046 containerd[1567]: time="2025-09-16T04:58:34.415820965Z" level=info msg="metadata content store policy set" policy=shared Sep 16 04:58:34.417287 kernel: EDAC MC: Ver: 3.0.0 Sep 16 04:58:34.421333 containerd[1567]: time="2025-09-16T04:58:34.421293774Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 16 04:58:34.421380 systemd-networkd[1448]: eth0: Gained IPv6LL Sep 16 04:58:34.421887 containerd[1567]: time="2025-09-16T04:58:34.421754376Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.421975324Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.421995723Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422018749Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422031618Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422046954Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422058162Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422076917Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422090367Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422100412Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 16 04:58:34.422816 containerd[1567]: time="2025-09-16T04:58:34.422114214Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 16 04:58:34.423101 containerd[1567]: time="2025-09-16T04:58:34.423081075Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 16 04:58:34.423154 containerd[1567]: time="2025-09-16T04:58:34.423144219Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 16 04:58:34.423211 containerd[1567]: time="2025-09-16T04:58:34.423199797Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 16 04:58:34.423278 containerd[1567]: time="2025-09-16T04:58:34.423267628Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 16 04:58:34.423710 containerd[1567]: time="2025-09-16T04:58:34.423694220Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 16 04:58:34.423779 containerd[1567]: time="2025-09-16T04:58:34.423755047Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 16 04:58:34.423855 containerd[1567]: time="2025-09-16T04:58:34.423842892Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 16 04:58:34.423905 containerd[1567]: time="2025-09-16T04:58:34.423895207Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 16 04:58:34.423947 containerd[1567]: time="2025-09-16T04:58:34.423938284Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 16 04:58:34.424012 containerd[1567]: time="2025-09-16T04:58:34.424001389Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 16 04:58:34.424054 containerd[1567]: time="2025-09-16T04:58:34.424045440Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 16 04:58:34.424179 containerd[1567]: time="2025-09-16T04:58:34.424162947Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 16 04:58:34.424939 containerd[1567]: time="2025-09-16T04:58:34.424802223Z" level=info msg="Start snapshots syncer" Sep 16 04:58:34.425010 containerd[1567]: time="2025-09-16T04:58:34.424995844Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 16 04:58:34.428100 containerd[1567]: time="2025-09-16T04:58:34.425908494Z" 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 16 04:58:34.428100 containerd[1567]: time="2025-09-16T04:58:34.425970582Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 16 04:58:34.427303 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428596145Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428857747Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428886313Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428898532Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428912696Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428927522Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428938675Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428964937Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.428995751Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.429009037Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 16 04:58:34.429065 containerd[1567]: time="2025-09-16T04:58:34.429022417Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 16 04:58:34.429414 containerd[1567]: time="2025-09-16T04:58:34.429397215Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.429914060Z" 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 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.429939462Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.429951633Z" 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 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.429959687Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.429970643Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.429982225Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.429999752Z" level=info msg="runtime interface created" Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.430005224Z" level=info msg="created NRI interface" Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.430013771Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.430033567Z" level=info msg="Connect containerd service" Sep 16 04:58:34.430360 containerd[1567]: time="2025-09-16T04:58:34.430072835Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 16 04:58:34.430405 systemd[1]: Reached target network-online.target - Network is Online. Sep 16 04:58:34.432724 containerd[1567]: time="2025-09-16T04:58:34.432693655Z" 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 16 04:58:34.437432 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 16 04:58:34.440334 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 16 04:58:34.449628 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 16 04:58:34.547437 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 16 04:58:34.728819 sshd_keygen[1568]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 16 04:58:34.737669 containerd[1567]: time="2025-09-16T04:58:34.737381325Z" level=info msg="Start subscribing containerd event" Sep 16 04:58:34.737669 containerd[1567]: time="2025-09-16T04:58:34.737463003Z" level=info msg="Start recovering state" Sep 16 04:58:34.740303 containerd[1567]: time="2025-09-16T04:58:34.737937548Z" level=info msg="Start event monitor" Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.740690691Z" level=info msg="Start cni network conf syncer for default" Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.740737037Z" level=info msg="Start streaming server" Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.740765537Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.740777047Z" level=info msg="runtime interface starting up..." Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.740786751Z" level=info msg="starting plugins..." Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.740818796Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.741179139Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 16 04:58:34.742297 containerd[1567]: time="2025-09-16T04:58:34.741414965Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 16 04:58:34.743286 containerd[1567]: time="2025-09-16T04:58:34.742776133Z" level=info msg="containerd successfully booted in 0.352438s" Sep 16 04:58:34.743239 systemd[1]: Started containerd.service - containerd container runtime. Sep 16 04:58:34.787745 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 16 04:58:34.793563 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 16 04:58:34.818735 systemd[1]: issuegen.service: Deactivated successfully. Sep 16 04:58:34.819339 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 16 04:58:34.828651 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 16 04:58:34.858595 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 16 04:58:34.863724 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 16 04:58:34.869621 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 16 04:58:34.871725 systemd[1]: Reached target getty.target - Login Prompts. Sep 16 04:58:34.997454 systemd-networkd[1448]: eth1: Gained IPv6LL Sep 16 04:58:35.892773 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 16 04:58:35.903111 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 16 04:58:35.911505 systemd[1]: Startup finished in 3.840s (kernel) + 6.342s (initrd) + 5.964s (userspace) = 16.147s. Sep 16 04:58:35.912809 (kubelet)[1675]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 16 04:58:36.647760 kubelet[1675]: E0916 04:58:36.647641 1675 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 16 04:58:36.654214 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 16 04:58:36.654514 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 16 04:58:36.655117 systemd[1]: kubelet.service: Consumed 1.597s CPU time, 265.6M memory peak. Sep 16 04:58:36.925588 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 16 04:58:36.926921 systemd[1]: Started sshd@0-64.23.250.0:22-139.178.89.65:42718.service - OpenSSH per-connection server daemon (139.178.89.65:42718). Sep 16 04:58:37.022426 sshd[1687]: Accepted publickey for core from 139.178.89.65 port 42718 ssh2: RSA SHA256:6eLXZOdlM1Fkhvg1RXWFuGKv/BaTEPNGMqBgfJBoRIs Sep 16 04:58:37.024718 sshd-session[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:37.038474 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 16 04:58:37.039741 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 16 04:58:37.045552 systemd-logind[1540]: New session 1 of user core. Sep 16 04:58:37.073197 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 16 04:58:37.076608 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 16 04:58:37.096462 (systemd)[1692]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 16 04:58:37.099914 systemd-logind[1540]: New session c1 of user core. Sep 16 04:58:37.311523 systemd[1692]: Queued start job for default target default.target. Sep 16 04:58:37.324109 systemd[1692]: Created slice app.slice - User Application Slice. Sep 16 04:58:37.324162 systemd[1692]: Reached target paths.target - Paths. Sep 16 04:58:37.324267 systemd[1692]: Reached target timers.target - Timers. Sep 16 04:58:37.326454 systemd[1692]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 16 04:58:37.340742 systemd[1692]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 16 04:58:37.341032 systemd[1692]: Reached target sockets.target - Sockets. Sep 16 04:58:37.341180 systemd[1692]: Reached target basic.target - Basic System. Sep 16 04:58:37.341333 systemd[1692]: Reached target default.target - Main User Target. Sep 16 04:58:37.341366 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 16 04:58:37.341462 systemd[1692]: Startup finished in 232ms. Sep 16 04:58:37.352882 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 16 04:58:37.422649 systemd[1]: Started sshd@1-64.23.250.0:22-139.178.89.65:42726.service - OpenSSH per-connection server daemon (139.178.89.65:42726). Sep 16 04:58:37.492477 sshd[1703]: Accepted publickey for core from 139.178.89.65 port 42726 ssh2: RSA SHA256:6eLXZOdlM1Fkhvg1RXWFuGKv/BaTEPNGMqBgfJBoRIs Sep 16 04:58:37.494295 sshd-session[1703]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:37.502003 systemd-logind[1540]: New session 2 of user core. Sep 16 04:58:37.511535 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 16 04:58:37.574007 sshd[1706]: Connection closed by 139.178.89.65 port 42726 Sep 16 04:58:37.574768 sshd-session[1703]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:37.588340 systemd[1]: sshd@1-64.23.250.0:22-139.178.89.65:42726.service: Deactivated successfully. Sep 16 04:58:37.590629 systemd[1]: session-2.scope: Deactivated successfully. Sep 16 04:58:37.631325 systemd-logind[1540]: Session 2 logged out. Waiting for processes to exit. Sep 16 04:58:37.634852 systemd[1]: Started sshd@2-64.23.250.0:22-139.178.89.65:42736.service - OpenSSH per-connection server daemon (139.178.89.65:42736). Sep 16 04:58:37.636086 systemd-logind[1540]: Removed session 2. Sep 16 04:58:37.702402 sshd[1712]: Accepted publickey for core from 139.178.89.65 port 42736 ssh2: RSA SHA256:6eLXZOdlM1Fkhvg1RXWFuGKv/BaTEPNGMqBgfJBoRIs Sep 16 04:58:37.703818 sshd-session[1712]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:37.710332 systemd-logind[1540]: New session 3 of user core. Sep 16 04:58:37.716559 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 16 04:58:37.770980 sshd[1715]: Connection closed by 139.178.89.65 port 42736 Sep 16 04:58:37.771638 sshd-session[1712]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:37.788886 systemd[1]: sshd@2-64.23.250.0:22-139.178.89.65:42736.service: Deactivated successfully. Sep 16 04:58:37.791080 systemd[1]: session-3.scope: Deactivated successfully. Sep 16 04:58:37.792179 systemd-logind[1540]: Session 3 logged out. Waiting for processes to exit. Sep 16 04:58:37.796302 systemd[1]: Started sshd@3-64.23.250.0:22-139.178.89.65:42744.service - OpenSSH per-connection server daemon (139.178.89.65:42744). Sep 16 04:58:37.797513 systemd-logind[1540]: Removed session 3. Sep 16 04:58:37.921587 sshd[1721]: Accepted publickey for core from 139.178.89.65 port 42744 ssh2: RSA SHA256:6eLXZOdlM1Fkhvg1RXWFuGKv/BaTEPNGMqBgfJBoRIs Sep 16 04:58:37.923732 sshd-session[1721]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:37.932186 systemd-logind[1540]: New session 4 of user core. Sep 16 04:58:37.937641 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 16 04:58:38.000349 sshd[1724]: Connection closed by 139.178.89.65 port 42744 Sep 16 04:58:38.001469 sshd-session[1721]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:38.011562 systemd[1]: sshd@3-64.23.250.0:22-139.178.89.65:42744.service: Deactivated successfully. Sep 16 04:58:38.013836 systemd[1]: session-4.scope: Deactivated successfully. Sep 16 04:58:38.014943 systemd-logind[1540]: Session 4 logged out. Waiting for processes to exit. Sep 16 04:58:38.017916 systemd-logind[1540]: Removed session 4. Sep 16 04:58:38.019660 systemd[1]: Started sshd@4-64.23.250.0:22-139.178.89.65:42758.service - OpenSSH per-connection server daemon (139.178.89.65:42758). Sep 16 04:58:38.093811 sshd[1730]: Accepted publickey for core from 139.178.89.65 port 42758 ssh2: RSA SHA256:6eLXZOdlM1Fkhvg1RXWFuGKv/BaTEPNGMqBgfJBoRIs Sep 16 04:58:38.095443 sshd-session[1730]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 16 04:58:38.102058 systemd-logind[1540]: New session 5 of user core. Sep 16 04:58:38.109585 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 16 04:58:38.178376 sudo[1734]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 16 04:58:38.178703 sudo[1734]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 16 04:58:38.912746 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 16 04:58:38.913075 systemd[1]: kubelet.service: Consumed 1.597s CPU time, 265.6M memory peak. Sep 16 04:58:38.916603 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 16 04:58:38.968203 systemd[1]: Reload requested from client PID 1767 ('systemctl') (unit session-5.scope)... Sep 16 04:58:38.968274 systemd[1]: Reloading... Sep 16 04:58:39.163275 zram_generator::config[1812]: No configuration found. Sep 16 04:58:39.534559 systemd[1]: Reloading finished in 565 ms. Sep 16 04:58:39.600096 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 16 04:58:39.600173 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 16 04:58:39.600475 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 16 04:58:39.600524 systemd[1]: kubelet.service: Consumed 161ms CPU time, 98.3M memory peak. Sep 16 04:58:39.603644 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 16 04:58:39.780170 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 16 04:58:39.799329 (kubelet)[1864]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 16 04:58:39.850270 kubelet[1864]: 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 16 04:58:39.850270 kubelet[1864]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 16 04:58:39.850270 kubelet[1864]: 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 16 04:58:39.850270 kubelet[1864]: I0916 04:58:39.850109 1864 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 16 04:58:40.194499 kubelet[1864]: I0916 04:58:40.194161 1864 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Sep 16 04:58:40.194499 kubelet[1864]: I0916 04:58:40.194332 1864 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 16 04:58:40.195274 kubelet[1864]: I0916 04:58:40.194987 1864 server.go:954] "Client rotation is on, will bootstrap in background" Sep 16 04:58:40.224321 kubelet[1864]: I0916 04:58:40.223347 1864 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 16 04:58:40.241046 kubelet[1864]: I0916 04:58:40.241014 1864 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 16 04:58:40.249043 kubelet[1864]: I0916 04:58:40.248686 1864 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 16 04:58:40.249760 kubelet[1864]: I0916 04:58:40.249704 1864 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 16 04:58:40.250039 kubelet[1864]: I0916 04:58:40.249852 1864 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"64.23.250.0","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 16 04:58:40.250181 kubelet[1864]: I0916 04:58:40.250171 1864 topology_manager.go:138] "Creating topology manager with none policy" Sep 16 04:58:40.250703 kubelet[1864]: I0916 04:58:40.250315 1864 container_manager_linux.go:304] "Creating device plugin manager" Sep 16 04:58:40.250703 kubelet[1864]: I0916 04:58:40.250495 1864 state_mem.go:36] "Initialized new in-memory state store" Sep 16 04:58:40.253831 kubelet[1864]: I0916 04:58:40.253792 1864 kubelet.go:446] "Attempting to sync node with API server" Sep 16 04:58:40.254347 kubelet[1864]: I0916 04:58:40.254328 1864 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 16 04:58:40.254463 kubelet[1864]: I0916 04:58:40.254443 1864 kubelet.go:352] "Adding apiserver pod source" Sep 16 04:58:40.254515 kubelet[1864]: I0916 04:58:40.254508 1864 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 16 04:58:40.257129 kubelet[1864]: E0916 04:58:40.257102 1864 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:40.258447 kubelet[1864]: I0916 04:58:40.257674 1864 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Sep 16 04:58:40.259045 kubelet[1864]: I0916 04:58:40.259011 1864 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 16 04:58:40.259499 kubelet[1864]: E0916 04:58:40.258409 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:40.259996 kubelet[1864]: W0916 04:58:40.259974 1864 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 16 04:58:40.262630 kubelet[1864]: I0916 04:58:40.262596 1864 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 16 04:58:40.262734 kubelet[1864]: I0916 04:58:40.262660 1864 server.go:1287] "Started kubelet" Sep 16 04:58:40.270366 kubelet[1864]: I0916 04:58:40.270308 1864 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 16 04:58:40.276629 kubelet[1864]: I0916 04:58:40.276420 1864 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Sep 16 04:58:40.278662 kubelet[1864]: I0916 04:58:40.278097 1864 server.go:479] "Adding debug handlers to kubelet server" Sep 16 04:58:40.280281 kubelet[1864]: I0916 04:58:40.280163 1864 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 16 04:58:40.280553 kubelet[1864]: I0916 04:58:40.280460 1864 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 16 04:58:40.284269 kubelet[1864]: I0916 04:58:40.280699 1864 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 16 04:58:40.285912 kubelet[1864]: E0916 04:58:40.284998 1864 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"64.23.250.0\" not found" Sep 16 04:58:40.288763 kubelet[1864]: I0916 04:58:40.287129 1864 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 16 04:58:40.288935 kubelet[1864]: I0916 04:58:40.287161 1864 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 16 04:58:40.288992 kubelet[1864]: I0916 04:58:40.288955 1864 factory.go:221] Registration of the systemd container factory successfully Sep 16 04:58:40.291266 kubelet[1864]: I0916 04:58:40.289428 1864 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 16 04:58:40.291730 kubelet[1864]: I0916 04:58:40.289088 1864 reconciler.go:26] "Reconciler: start to sync state" Sep 16 04:58:40.291730 kubelet[1864]: E0916 04:58:40.289906 1864 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 16 04:58:40.291730 kubelet[1864]: E0916 04:58:40.290215 1864 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"64.23.250.0\" not found" node="64.23.250.0" Sep 16 04:58:40.295081 kubelet[1864]: I0916 04:58:40.295026 1864 factory.go:221] Registration of the containerd container factory successfully Sep 16 04:58:40.329532 kubelet[1864]: I0916 04:58:40.329479 1864 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 16 04:58:40.329532 kubelet[1864]: I0916 04:58:40.329510 1864 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 16 04:58:40.330006 kubelet[1864]: I0916 04:58:40.329979 1864 state_mem.go:36] "Initialized new in-memory state store" Sep 16 04:58:40.334012 kubelet[1864]: I0916 04:58:40.333377 1864 policy_none.go:49] "None policy: Start" Sep 16 04:58:40.334012 kubelet[1864]: I0916 04:58:40.333405 1864 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 16 04:58:40.334012 kubelet[1864]: I0916 04:58:40.333417 1864 state_mem.go:35] "Initializing new in-memory state store" Sep 16 04:58:40.342491 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 16 04:58:40.356163 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 16 04:58:40.363470 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 16 04:58:40.374186 kubelet[1864]: I0916 04:58:40.373979 1864 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 16 04:58:40.376685 kubelet[1864]: I0916 04:58:40.376653 1864 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 16 04:58:40.378436 kubelet[1864]: I0916 04:58:40.376988 1864 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 16 04:58:40.379060 kubelet[1864]: I0916 04:58:40.378994 1864 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 16 04:58:40.381453 kubelet[1864]: E0916 04:58:40.381412 1864 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 16 04:58:40.381814 kubelet[1864]: E0916 04:58:40.381795 1864 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"64.23.250.0\" not found" Sep 16 04:58:40.394262 kubelet[1864]: I0916 04:58:40.394197 1864 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 16 04:58:40.397818 kubelet[1864]: I0916 04:58:40.397360 1864 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 16 04:58:40.397818 kubelet[1864]: I0916 04:58:40.397396 1864 status_manager.go:227] "Starting to sync pod status with apiserver" Sep 16 04:58:40.397818 kubelet[1864]: I0916 04:58:40.397418 1864 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 16 04:58:40.397818 kubelet[1864]: I0916 04:58:40.397424 1864 kubelet.go:2382] "Starting kubelet main sync loop" Sep 16 04:58:40.397818 kubelet[1864]: E0916 04:58:40.397480 1864 kubelet.go:2406] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 16 04:58:40.482954 kubelet[1864]: I0916 04:58:40.482815 1864 kubelet_node_status.go:75] "Attempting to register node" node="64.23.250.0" Sep 16 04:58:40.489786 kubelet[1864]: I0916 04:58:40.489740 1864 kubelet_node_status.go:78] "Successfully registered node" node="64.23.250.0" Sep 16 04:58:40.513234 kubelet[1864]: I0916 04:58:40.513197 1864 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 16 04:58:40.514157 containerd[1567]: time="2025-09-16T04:58:40.513975064Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 16 04:58:40.514874 kubelet[1864]: I0916 04:58:40.514442 1864 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 16 04:58:40.828514 sudo[1734]: pam_unix(sudo:session): session closed for user root Sep 16 04:58:40.831696 sshd[1733]: Connection closed by 139.178.89.65 port 42758 Sep 16 04:58:40.832327 sshd-session[1730]: pam_unix(sshd:session): session closed for user core Sep 16 04:58:40.835923 systemd-logind[1540]: Session 5 logged out. Waiting for processes to exit. Sep 16 04:58:40.836772 systemd[1]: sshd@4-64.23.250.0:22-139.178.89.65:42758.service: Deactivated successfully. Sep 16 04:58:40.839335 systemd[1]: session-5.scope: Deactivated successfully. Sep 16 04:58:40.839672 systemd[1]: session-5.scope: Consumed 684ms CPU time, 74.1M memory peak. Sep 16 04:58:40.843218 systemd-logind[1540]: Removed session 5. Sep 16 04:58:41.197299 kubelet[1864]: I0916 04:58:41.197060 1864 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 16 04:58:41.197807 kubelet[1864]: W0916 04:58:41.197397 1864 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 16 04:58:41.197807 kubelet[1864]: W0916 04:58:41.197727 1864 reflector.go:492] 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 16 04:58:41.197807 kubelet[1864]: W0916 04:58:41.197759 1864 reflector.go:492] 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 16 04:58:41.259228 kubelet[1864]: I0916 04:58:41.259167 1864 apiserver.go:52] "Watching apiserver" Sep 16 04:58:41.259609 kubelet[1864]: E0916 04:58:41.259545 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:41.273610 systemd[1]: Created slice kubepods-besteffort-poda170c3b0_817f_4dc9_8875_2a2969a39c05.slice - libcontainer container kubepods-besteffort-poda170c3b0_817f_4dc9_8875_2a2969a39c05.slice. Sep 16 04:58:41.289458 kubelet[1864]: I0916 04:58:41.289411 1864 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 16 04:58:41.297541 systemd[1]: Created slice kubepods-burstable-pod79c75b16_e560_4c13_bf77_7dd54856fc13.slice - libcontainer container kubepods-burstable-pod79c75b16_e560_4c13_bf77_7dd54856fc13.slice. Sep 16 04:58:41.318579 kubelet[1864]: I0916 04:58:41.318529 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/79c75b16-e560-4c13-bf77-7dd54856fc13-run\") pod \"kube-flannel-ds-qw7mm\" (UID: \"79c75b16-e560-4c13-bf77-7dd54856fc13\") " pod="kube-flannel/kube-flannel-ds-qw7mm" Sep 16 04:58:41.318917 kubelet[1864]: I0916 04:58:41.318834 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/79c75b16-e560-4c13-bf77-7dd54856fc13-cni-plugin\") pod \"kube-flannel-ds-qw7mm\" (UID: \"79c75b16-e560-4c13-bf77-7dd54856fc13\") " pod="kube-flannel/kube-flannel-ds-qw7mm" Sep 16 04:58:41.319055 kubelet[1864]: I0916 04:58:41.318967 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/79c75b16-e560-4c13-bf77-7dd54856fc13-flannel-cfg\") pod \"kube-flannel-ds-qw7mm\" (UID: \"79c75b16-e560-4c13-bf77-7dd54856fc13\") " pod="kube-flannel/kube-flannel-ds-qw7mm" Sep 16 04:58:41.319055 kubelet[1864]: I0916 04:58:41.318989 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/a170c3b0-817f-4dc9-8875-2a2969a39c05-kube-proxy\") pod \"kube-proxy-58pv2\" (UID: \"a170c3b0-817f-4dc9-8875-2a2969a39c05\") " pod="kube-system/kube-proxy-58pv2" Sep 16 04:58:41.319187 kubelet[1864]: I0916 04:58:41.319144 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a170c3b0-817f-4dc9-8875-2a2969a39c05-xtables-lock\") pod \"kube-proxy-58pv2\" (UID: \"a170c3b0-817f-4dc9-8875-2a2969a39c05\") " pod="kube-system/kube-proxy-58pv2" Sep 16 04:58:41.319187 kubelet[1864]: I0916 04:58:41.319167 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/79c75b16-e560-4c13-bf77-7dd54856fc13-cni\") pod \"kube-flannel-ds-qw7mm\" (UID: \"79c75b16-e560-4c13-bf77-7dd54856fc13\") " pod="kube-flannel/kube-flannel-ds-qw7mm" Sep 16 04:58:41.319408 kubelet[1864]: I0916 04:58:41.319364 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/79c75b16-e560-4c13-bf77-7dd54856fc13-xtables-lock\") pod \"kube-flannel-ds-qw7mm\" (UID: \"79c75b16-e560-4c13-bf77-7dd54856fc13\") " pod="kube-flannel/kube-flannel-ds-qw7mm" Sep 16 04:58:41.319408 kubelet[1864]: I0916 04:58:41.319388 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-b2t68\" (UniqueName: \"kubernetes.io/projected/79c75b16-e560-4c13-bf77-7dd54856fc13-kube-api-access-b2t68\") pod \"kube-flannel-ds-qw7mm\" (UID: \"79c75b16-e560-4c13-bf77-7dd54856fc13\") " pod="kube-flannel/kube-flannel-ds-qw7mm" Sep 16 04:58:41.319575 kubelet[1864]: I0916 04:58:41.319519 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/a170c3b0-817f-4dc9-8875-2a2969a39c05-lib-modules\") pod \"kube-proxy-58pv2\" (UID: \"a170c3b0-817f-4dc9-8875-2a2969a39c05\") " pod="kube-system/kube-proxy-58pv2" Sep 16 04:58:41.319575 kubelet[1864]: I0916 04:58:41.319539 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kcx9f\" (UniqueName: \"kubernetes.io/projected/a170c3b0-817f-4dc9-8875-2a2969a39c05-kube-api-access-kcx9f\") pod \"kube-proxy-58pv2\" (UID: \"a170c3b0-817f-4dc9-8875-2a2969a39c05\") " pod="kube-system/kube-proxy-58pv2" Sep 16 04:58:41.593153 kubelet[1864]: E0916 04:58:41.592772 1864 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 16 04:58:41.594014 containerd[1567]: time="2025-09-16T04:58:41.593741831Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-58pv2,Uid:a170c3b0-817f-4dc9-8875-2a2969a39c05,Namespace:kube-system,Attempt:0,}" Sep 16 04:58:41.606776 kubelet[1864]: E0916 04:58:41.606707 1864 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 16 04:58:41.607609 containerd[1567]: time="2025-09-16T04:58:41.607418752Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-qw7mm,Uid:79c75b16-e560-4c13-bf77-7dd54856fc13,Namespace:kube-flannel,Attempt:0,}" Sep 16 04:58:42.103569 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3877360392.mount: Deactivated successfully. Sep 16 04:58:42.115295 containerd[1567]: time="2025-09-16T04:58:42.114628781Z" 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 16 04:58:42.116344 containerd[1567]: time="2025-09-16T04:58:42.116283172Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" Sep 16 04:58:42.118295 containerd[1567]: time="2025-09-16T04:58:42.118237672Z" 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 16 04:58:42.119354 containerd[1567]: time="2025-09-16T04:58:42.119060501Z" 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 16 04:58:42.119354 containerd[1567]: time="2025-09-16T04:58:42.119307678Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 16 04:58:42.121532 containerd[1567]: time="2025-09-16T04:58:42.121493811Z" 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 16 04:58:42.122300 containerd[1567]: time="2025-09-16T04:58:42.122270759Z" 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 521.525027ms" Sep 16 04:58:42.123540 containerd[1567]: time="2025-09-16T04:58:42.123489146Z" 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 510.804294ms" Sep 16 04:58:42.149652 containerd[1567]: time="2025-09-16T04:58:42.149598689Z" level=info msg="connecting to shim 0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309" address="unix:///run/containerd/s/2cacdb0bb16b21d4860eee0dc4f0af3cd6b4d65b26a4b3b5d6934f149c6ea4b3" namespace=k8s.io protocol=ttrpc version=3 Sep 16 04:58:42.151717 containerd[1567]: time="2025-09-16T04:58:42.151409194Z" level=info msg="connecting to shim c068a6c1e7fd480d7d13bbfb73c4f9db3187c274a9235f78484c3042665fdf66" address="unix:///run/containerd/s/fd2ba6564ac7397fe67af00b6c921d3ba5a5293bf7f7415866d95df5bacc6861" namespace=k8s.io protocol=ttrpc version=3 Sep 16 04:58:42.191796 systemd[1]: Started cri-containerd-0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309.scope - libcontainer container 0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309. Sep 16 04:58:42.206745 systemd[1]: Started cri-containerd-c068a6c1e7fd480d7d13bbfb73c4f9db3187c274a9235f78484c3042665fdf66.scope - libcontainer container c068a6c1e7fd480d7d13bbfb73c4f9db3187c274a9235f78484c3042665fdf66. Sep 16 04:58:42.260049 kubelet[1864]: E0916 04:58:42.259926 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:42.266784 containerd[1567]: time="2025-09-16T04:58:42.266740100Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-58pv2,Uid:a170c3b0-817f-4dc9-8875-2a2969a39c05,Namespace:kube-system,Attempt:0,} returns sandbox id \"c068a6c1e7fd480d7d13bbfb73c4f9db3187c274a9235f78484c3042665fdf66\"" Sep 16 04:58:42.269951 kubelet[1864]: E0916 04:58:42.269467 1864 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 16 04:58:42.271236 containerd[1567]: time="2025-09-16T04:58:42.271172056Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\"" Sep 16 04:58:42.296402 containerd[1567]: time="2025-09-16T04:58:42.296351861Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-qw7mm,Uid:79c75b16-e560-4c13-bf77-7dd54856fc13,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309\"" Sep 16 04:58:42.297208 kubelet[1864]: E0916 04:58:42.297182 1864 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 16 04:58:42.951553 systemd[1]: Started sshd@5-64.23.250.0:22-116.196.70.63:34062.service - OpenSSH per-connection server daemon (116.196.70.63:34062). Sep 16 04:58:43.261594 kubelet[1864]: E0916 04:58:43.261449 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:43.405170 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4063475248.mount: Deactivated successfully. Sep 16 04:58:44.004155 containerd[1567]: time="2025-09-16T04:58:44.004087107Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:44.005330 containerd[1567]: time="2025-09-16T04:58:44.005078094Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.9: active requests=0, bytes read=30924206" Sep 16 04:58:44.006005 containerd[1567]: time="2025-09-16T04:58:44.005974276Z" level=info msg="ImageCreate event name:\"sha256:fa3fdca615a501743d8deb39729a96e731312aac8d96accec061d5265360332f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:44.007776 containerd[1567]: time="2025-09-16T04:58:44.007746099Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:44.008826 containerd[1567]: time="2025-09-16T04:58:44.008728873Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.9\" with image id \"sha256:fa3fdca615a501743d8deb39729a96e731312aac8d96accec061d5265360332f\", repo tag \"registry.k8s.io/kube-proxy:v1.32.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:886af02535dc34886e4618b902f8c140d89af57233a245621d29642224516064\", size \"30923225\" in 1.737351375s" Sep 16 04:58:44.008826 containerd[1567]: time="2025-09-16T04:58:44.008773190Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.9\" returns image reference \"sha256:fa3fdca615a501743d8deb39729a96e731312aac8d96accec061d5265360332f\"" Sep 16 04:58:44.010819 containerd[1567]: time="2025-09-16T04:58:44.010773107Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 16 04:58:44.013136 containerd[1567]: time="2025-09-16T04:58:44.013096378Z" level=info msg="CreateContainer within sandbox \"c068a6c1e7fd480d7d13bbfb73c4f9db3187c274a9235f78484c3042665fdf66\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 16 04:58:44.028280 containerd[1567]: time="2025-09-16T04:58:44.027331273Z" level=info msg="Container 985c6330f4fdd10ba2ec462589db488395a9f8ae9cdbfe126ecb0170c3d854aa: CDI devices from CRI Config.CDIDevices: []" Sep 16 04:58:44.029637 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1661620411.mount: Deactivated successfully. Sep 16 04:58:44.039905 containerd[1567]: time="2025-09-16T04:58:44.039751907Z" level=info msg="CreateContainer within sandbox \"c068a6c1e7fd480d7d13bbfb73c4f9db3187c274a9235f78484c3042665fdf66\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"985c6330f4fdd10ba2ec462589db488395a9f8ae9cdbfe126ecb0170c3d854aa\"" Sep 16 04:58:44.041406 containerd[1567]: time="2025-09-16T04:58:44.041299710Z" level=info msg="StartContainer for \"985c6330f4fdd10ba2ec462589db488395a9f8ae9cdbfe126ecb0170c3d854aa\"" Sep 16 04:58:44.043422 containerd[1567]: time="2025-09-16T04:58:44.043345600Z" level=info msg="connecting to shim 985c6330f4fdd10ba2ec462589db488395a9f8ae9cdbfe126ecb0170c3d854aa" address="unix:///run/containerd/s/fd2ba6564ac7397fe67af00b6c921d3ba5a5293bf7f7415866d95df5bacc6861" protocol=ttrpc version=3 Sep 16 04:58:44.081563 systemd[1]: Started cri-containerd-985c6330f4fdd10ba2ec462589db488395a9f8ae9cdbfe126ecb0170c3d854aa.scope - libcontainer container 985c6330f4fdd10ba2ec462589db488395a9f8ae9cdbfe126ecb0170c3d854aa. Sep 16 04:58:44.159059 containerd[1567]: time="2025-09-16T04:58:44.158944531Z" level=info msg="StartContainer for \"985c6330f4fdd10ba2ec462589db488395a9f8ae9cdbfe126ecb0170c3d854aa\" returns successfully" Sep 16 04:58:44.261723 kubelet[1864]: E0916 04:58:44.261585 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:44.414478 kubelet[1864]: E0916 04:58:44.414427 1864 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 16 04:58:45.262029 kubelet[1864]: E0916 04:58:45.261943 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:45.417563 kubelet[1864]: E0916 04:58:45.417467 1864 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 16 04:58:45.937100 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1748632707.mount: Deactivated successfully. Sep 16 04:58:45.989191 containerd[1567]: time="2025-09-16T04:58:45.988398442Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:45.989191 containerd[1567]: time="2025-09-16T04:58:45.989131917Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Sep 16 04:58:45.990024 containerd[1567]: time="2025-09-16T04:58:45.989965066Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:45.993543 containerd[1567]: time="2025-09-16T04:58:45.993474810Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:45.995394 containerd[1567]: time="2025-09-16T04:58:45.995329819Z" 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 1.984504806s" Sep 16 04:58:45.995792 containerd[1567]: time="2025-09-16T04:58:45.995616230Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Sep 16 04:58:45.998774 containerd[1567]: time="2025-09-16T04:58:45.998710425Z" level=info msg="CreateContainer within sandbox \"0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 16 04:58:46.008305 containerd[1567]: time="2025-09-16T04:58:46.006666629Z" level=info msg="Container 8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5: CDI devices from CRI Config.CDIDevices: []" Sep 16 04:58:46.022958 containerd[1567]: time="2025-09-16T04:58:46.022872429Z" level=info msg="CreateContainer within sandbox \"0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5\"" Sep 16 04:58:46.024082 containerd[1567]: time="2025-09-16T04:58:46.024010351Z" level=info msg="StartContainer for \"8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5\"" Sep 16 04:58:46.025660 containerd[1567]: time="2025-09-16T04:58:46.025560823Z" level=info msg="connecting to shim 8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5" address="unix:///run/containerd/s/2cacdb0bb16b21d4860eee0dc4f0af3cd6b4d65b26a4b3b5d6934f149c6ea4b3" protocol=ttrpc version=3 Sep 16 04:58:46.063580 systemd[1]: Started cri-containerd-8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5.scope - libcontainer container 8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5. Sep 16 04:58:46.107018 systemd[1]: cri-containerd-8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5.scope: Deactivated successfully. Sep 16 04:58:46.109947 containerd[1567]: time="2025-09-16T04:58:46.109845138Z" level=info msg="TaskExit event in podsandbox handler container_id:\"8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5\" id:\"8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5\" pid:2196 exited_at:{seconds:1757998726 nanos:108823106}" Sep 16 04:58:46.110676 containerd[1567]: time="2025-09-16T04:58:46.110624272Z" level=info msg="received exit event container_id:\"8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5\" id:\"8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5\" pid:2196 exited_at:{seconds:1757998726 nanos:108823106}" Sep 16 04:58:46.113167 containerd[1567]: time="2025-09-16T04:58:46.113116501Z" level=info msg="StartContainer for \"8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5\" returns successfully" Sep 16 04:58:46.262819 kubelet[1864]: E0916 04:58:46.262678 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:46.422019 kubelet[1864]: E0916 04:58:46.421939 1864 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 16 04:58:46.423119 containerd[1567]: time="2025-09-16T04:58:46.423058961Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 16 04:58:46.452053 kubelet[1864]: I0916 04:58:46.451954 1864 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-58pv2" podStartSLOduration=4.712585892 podStartE2EDuration="6.451927889s" podCreationTimestamp="2025-09-16 04:58:40 +0000 UTC" firstStartedPulling="2025-09-16 04:58:42.270572229 +0000 UTC m=+2.467373378" lastFinishedPulling="2025-09-16 04:58:44.009914238 +0000 UTC m=+4.206715375" observedRunningTime="2025-09-16 04:58:44.428983772 +0000 UTC m=+4.625784928" watchObservedRunningTime="2025-09-16 04:58:46.451927889 +0000 UTC m=+6.648729030" Sep 16 04:58:46.836975 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-8c39072037a33cd78e2e46a2cf9feca33c0342c99ff21aca96ac1af482565ed5-rootfs.mount: Deactivated successfully. Sep 16 04:58:47.264342 kubelet[1864]: E0916 04:58:47.264232 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:47.733596 systemd-resolved[1400]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.3. Sep 16 04:58:48.265003 kubelet[1864]: E0916 04:58:48.264851 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:48.283375 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3054957225.mount: Deactivated successfully. Sep 16 04:58:49.183371 containerd[1567]: time="2025-09-16T04:58:49.183285656Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:49.184668 containerd[1567]: time="2025-09-16T04:58:49.184597472Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Sep 16 04:58:49.185290 containerd[1567]: time="2025-09-16T04:58:49.185224611Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:49.188962 containerd[1567]: time="2025-09-16T04:58:49.188917906Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:49.190644 containerd[1567]: time="2025-09-16T04:58:49.190592410Z" 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.767303104s" Sep 16 04:58:49.190644 containerd[1567]: time="2025-09-16T04:58:49.190634339Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Sep 16 04:58:49.193658 containerd[1567]: time="2025-09-16T04:58:49.193588113Z" level=info msg="CreateContainer within sandbox \"0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 16 04:58:49.202925 containerd[1567]: time="2025-09-16T04:58:49.202873132Z" level=info msg="Container a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474: CDI devices from CRI Config.CDIDevices: []" Sep 16 04:58:49.212648 containerd[1567]: time="2025-09-16T04:58:49.212574162Z" level=info msg="CreateContainer within sandbox \"0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474\"" Sep 16 04:58:49.213646 containerd[1567]: time="2025-09-16T04:58:49.213609491Z" level=info msg="StartContainer for \"a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474\"" Sep 16 04:58:49.215011 containerd[1567]: time="2025-09-16T04:58:49.214939044Z" level=info msg="connecting to shim a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474" address="unix:///run/containerd/s/2cacdb0bb16b21d4860eee0dc4f0af3cd6b4d65b26a4b3b5d6934f149c6ea4b3" protocol=ttrpc version=3 Sep 16 04:58:49.242603 systemd[1]: Started cri-containerd-a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474.scope - libcontainer container a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474. Sep 16 04:58:49.265496 kubelet[1864]: E0916 04:58:49.265415 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:49.291370 systemd[1]: cri-containerd-a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474.scope: Deactivated successfully. Sep 16 04:58:49.294069 containerd[1567]: time="2025-09-16T04:58:49.293974911Z" level=info msg="TaskExit event in podsandbox handler container_id:\"a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474\" id:\"a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474\" pid:2268 exited_at:{seconds:1757998729 nanos:292442393}" Sep 16 04:58:49.297215 containerd[1567]: time="2025-09-16T04:58:49.296991431Z" level=info msg="received exit event container_id:\"a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474\" id:\"a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474\" pid:2268 exited_at:{seconds:1757998729 nanos:292442393}" Sep 16 04:58:49.299129 containerd[1567]: time="2025-09-16T04:58:49.299090837Z" level=info msg="StartContainer for \"a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474\" returns successfully" Sep 16 04:58:49.335671 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a0ba21b5be59bddd75d1e8379aa0309650218653d18fa71976ed896537074474-rootfs.mount: Deactivated successfully. Sep 16 04:58:49.359038 kubelet[1864]: I0916 04:58:49.358855 1864 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 16 04:58:49.434208 kubelet[1864]: E0916 04:58:49.433961 1864 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 16 04:58:50.265741 kubelet[1864]: E0916 04:58:50.265655 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:50.439171 kubelet[1864]: E0916 04:58:50.439114 1864 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 16 04:58:50.441983 containerd[1567]: time="2025-09-16T04:58:50.441905625Z" level=info msg="CreateContainer within sandbox \"0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 16 04:58:50.454268 containerd[1567]: time="2025-09-16T04:58:50.454035883Z" level=info msg="Container 0f5e9a2681810a525366749640b1014f87aae0a83a3617c35e23b03980d2ac01: CDI devices from CRI Config.CDIDevices: []" Sep 16 04:58:50.467402 containerd[1567]: time="2025-09-16T04:58:50.467351285Z" level=info msg="CreateContainer within sandbox \"0efb48621f8b8da5eef697a611dc51b3801ea7403a48fb0b15c30b9247ef1309\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"0f5e9a2681810a525366749640b1014f87aae0a83a3617c35e23b03980d2ac01\"" Sep 16 04:58:50.468622 containerd[1567]: time="2025-09-16T04:58:50.468578759Z" level=info msg="StartContainer for \"0f5e9a2681810a525366749640b1014f87aae0a83a3617c35e23b03980d2ac01\"" Sep 16 04:58:50.470377 containerd[1567]: time="2025-09-16T04:58:50.470296372Z" level=info msg="connecting to shim 0f5e9a2681810a525366749640b1014f87aae0a83a3617c35e23b03980d2ac01" address="unix:///run/containerd/s/2cacdb0bb16b21d4860eee0dc4f0af3cd6b4d65b26a4b3b5d6934f149c6ea4b3" protocol=ttrpc version=3 Sep 16 04:58:50.507617 systemd[1]: Started cri-containerd-0f5e9a2681810a525366749640b1014f87aae0a83a3617c35e23b03980d2ac01.scope - libcontainer container 0f5e9a2681810a525366749640b1014f87aae0a83a3617c35e23b03980d2ac01. Sep 16 04:58:50.548467 containerd[1567]: time="2025-09-16T04:58:50.548191480Z" level=info msg="StartContainer for \"0f5e9a2681810a525366749640b1014f87aae0a83a3617c35e23b03980d2ac01\" returns successfully" Sep 16 04:58:50.805560 systemd-resolved[1400]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. Sep 16 04:58:51.266768 kubelet[1864]: E0916 04:58:51.266701 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:51.445128 kubelet[1864]: E0916 04:58:51.445069 1864 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 16 04:58:51.461152 kubelet[1864]: I0916 04:58:51.460969 1864 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-qw7mm" podStartSLOduration=4.567104075 podStartE2EDuration="11.460947399s" podCreationTimestamp="2025-09-16 04:58:40 +0000 UTC" firstStartedPulling="2025-09-16 04:58:42.297830799 +0000 UTC m=+2.494631950" lastFinishedPulling="2025-09-16 04:58:49.191674139 +0000 UTC m=+9.388475274" observedRunningTime="2025-09-16 04:58:51.460089724 +0000 UTC m=+11.656890887" watchObservedRunningTime="2025-09-16 04:58:51.460947399 +0000 UTC m=+11.657748570" Sep 16 04:58:51.612415 systemd-networkd[1448]: flannel.1: Link UP Sep 16 04:58:51.612430 systemd-networkd[1448]: flannel.1: Gained carrier Sep 16 04:58:51.906365 kubelet[1864]: W0916 04:58:51.905430 1864 reflector.go:569] object-"default"/"kube-root-ca.crt": failed to list *v1.ConfigMap: configmaps "kube-root-ca.crt" is forbidden: User "system:node:64.23.250.0" cannot list resource "configmaps" in API group "" in the namespace "default": no relationship found between node '64.23.250.0' and this object Sep 16 04:58:51.906365 kubelet[1864]: E0916 04:58:51.905669 1864 reflector.go:166] "Unhandled Error" err="object-\"default\"/\"kube-root-ca.crt\": Failed to watch *v1.ConfigMap: failed to list *v1.ConfigMap: configmaps \"kube-root-ca.crt\" is forbidden: User \"system:node:64.23.250.0\" cannot list resource \"configmaps\" in API group \"\" in the namespace \"default\": no relationship found between node '64.23.250.0' and this object" logger="UnhandledError" Sep 16 04:58:51.906365 kubelet[1864]: I0916 04:58:51.905484 1864 status_manager.go:890] "Failed to get status for pod" podUID="48aa2c5f-dac7-42f6-930b-77a2b9065a1a" pod="default/nginx-deployment-7fcdb87857-28glz" err="pods \"nginx-deployment-7fcdb87857-28glz\" is forbidden: User \"system:node:64.23.250.0\" cannot get resource \"pods\" in API group \"\" in the namespace \"default\": no relationship found between node '64.23.250.0' and this object" Sep 16 04:58:51.909826 systemd[1]: Created slice kubepods-besteffort-pod48aa2c5f_dac7_42f6_930b_77a2b9065a1a.slice - libcontainer container kubepods-besteffort-pod48aa2c5f_dac7_42f6_930b_77a2b9065a1a.slice. Sep 16 04:58:51.984077 kubelet[1864]: I0916 04:58:51.983993 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jpvv2\" (UniqueName: \"kubernetes.io/projected/48aa2c5f-dac7-42f6-930b-77a2b9065a1a-kube-api-access-jpvv2\") pod \"nginx-deployment-7fcdb87857-28glz\" (UID: \"48aa2c5f-dac7-42f6-930b-77a2b9065a1a\") " pod="default/nginx-deployment-7fcdb87857-28glz" Sep 16 04:58:52.266955 kubelet[1864]: E0916 04:58:52.266873 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:52.448334 kubelet[1864]: E0916 04:58:52.448274 1864 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 16 04:58:53.114357 containerd[1567]: time="2025-09-16T04:58:53.114221847Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-28glz,Uid:48aa2c5f-dac7-42f6-930b-77a2b9065a1a,Namespace:default,Attempt:0,}" Sep 16 04:58:53.133370 systemd-networkd[1448]: cni0: Link UP Sep 16 04:58:53.144313 kernel: cni0: port 1(vethfa5957b5) entered blocking state Sep 16 04:58:53.144422 kernel: cni0: port 1(vethfa5957b5) entered disabled state Sep 16 04:58:53.147707 systemd-networkd[1448]: vethfa5957b5: Link UP Sep 16 04:58:53.149402 kernel: vethfa5957b5: entered allmulticast mode Sep 16 04:58:53.151663 kernel: vethfa5957b5: entered promiscuous mode Sep 16 04:58:53.161595 kernel: cni0: port 1(vethfa5957b5) entered blocking state Sep 16 04:58:53.161785 kernel: cni0: port 1(vethfa5957b5) entered forwarding state Sep 16 04:58:53.162539 systemd-networkd[1448]: vethfa5957b5: Gained carrier Sep 16 04:58:53.163701 systemd-networkd[1448]: cni0: Gained carrier Sep 16 04:58:53.173293 containerd[1567]: 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 16 04:58:53.173293 containerd[1567]: delegateAdd: netconf sent to delegate plugin: Sep 16 04:58:53.210608 containerd[1567]: {"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-16T04:58:53.210511821Z" level=info msg="connecting to shim a88d68a9ee431e1281f3c2b1c7ad97cba2303c4257f503b37a4dbeb421877771" address="unix:///run/containerd/s/f26f95355db5ff382d4fa9ab84dcb0b4e4fa887bf09cffaae6939ce2744eeb97" namespace=k8s.io protocol=ttrpc version=3 Sep 16 04:58:53.252618 systemd[1]: Started cri-containerd-a88d68a9ee431e1281f3c2b1c7ad97cba2303c4257f503b37a4dbeb421877771.scope - libcontainer container a88d68a9ee431e1281f3c2b1c7ad97cba2303c4257f503b37a4dbeb421877771. Sep 16 04:58:53.267099 kubelet[1864]: E0916 04:58:53.267047 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:53.307703 containerd[1567]: time="2025-09-16T04:58:53.307657172Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-28glz,Uid:48aa2c5f-dac7-42f6-930b-77a2b9065a1a,Namespace:default,Attempt:0,} returns sandbox id \"a88d68a9ee431e1281f3c2b1c7ad97cba2303c4257f503b37a4dbeb421877771\"" Sep 16 04:58:53.309322 containerd[1567]: time="2025-09-16T04:58:53.309239625Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 16 04:58:53.493551 systemd-networkd[1448]: flannel.1: Gained IPv6LL Sep 16 04:58:53.877586 systemd-resolved[1400]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.3. Sep 16 04:58:54.267447 kubelet[1864]: E0916 04:58:54.267372 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:54.837559 systemd-networkd[1448]: cni0: Gained IPv6LL Sep 16 04:58:55.157439 systemd-networkd[1448]: vethfa5957b5: Gained IPv6LL Sep 16 04:58:55.268536 kubelet[1864]: E0916 04:58:55.268473 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:56.178185 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2933149529.mount: Deactivated successfully. Sep 16 04:58:56.268934 kubelet[1864]: E0916 04:58:56.268874 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:57.269884 kubelet[1864]: E0916 04:58:57.269793 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:57.829213 containerd[1567]: time="2025-09-16T04:58:57.829107252Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:57.831006 containerd[1567]: time="2025-09-16T04:58:57.830955249Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73310444" Sep 16 04:58:57.832268 containerd[1567]: time="2025-09-16T04:58:57.831969511Z" level=info msg="ImageCreate event name:\"sha256:3faf4e6888eeaa3974f71cfac4283b6b674433e2d0e91ef709d1a4a2643bd7cc\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:57.834589 containerd[1567]: time="2025-09-16T04:58:57.834544808Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:f58d36003a8cbcd81a7136799a0563e0c5c8de28ce4b3386dd52dc5370c4b893\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:58:57.835835 containerd[1567]: time="2025-09-16T04:58:57.835797089Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:3faf4e6888eeaa3974f71cfac4283b6b674433e2d0e91ef709d1a4a2643bd7cc\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:f58d36003a8cbcd81a7136799a0563e0c5c8de28ce4b3386dd52dc5370c4b893\", size \"73310322\" in 4.526470224s" Sep 16 04:58:57.835956 containerd[1567]: time="2025-09-16T04:58:57.835939192Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:3faf4e6888eeaa3974f71cfac4283b6b674433e2d0e91ef709d1a4a2643bd7cc\"" Sep 16 04:58:57.838964 containerd[1567]: time="2025-09-16T04:58:57.838908748Z" level=info msg="CreateContainer within sandbox \"a88d68a9ee431e1281f3c2b1c7ad97cba2303c4257f503b37a4dbeb421877771\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 16 04:58:57.851152 containerd[1567]: time="2025-09-16T04:58:57.851100535Z" level=info msg="Container 91fa08f5f50606a3ad3c582388e8a8060ba6f175f71a7b57cb6d2061ce45a123: CDI devices from CRI Config.CDIDevices: []" Sep 16 04:58:57.862368 containerd[1567]: time="2025-09-16T04:58:57.862306352Z" level=info msg="CreateContainer within sandbox \"a88d68a9ee431e1281f3c2b1c7ad97cba2303c4257f503b37a4dbeb421877771\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"91fa08f5f50606a3ad3c582388e8a8060ba6f175f71a7b57cb6d2061ce45a123\"" Sep 16 04:58:57.863412 containerd[1567]: time="2025-09-16T04:58:57.863374364Z" level=info msg="StartContainer for \"91fa08f5f50606a3ad3c582388e8a8060ba6f175f71a7b57cb6d2061ce45a123\"" Sep 16 04:58:57.864496 containerd[1567]: time="2025-09-16T04:58:57.864460081Z" level=info msg="connecting to shim 91fa08f5f50606a3ad3c582388e8a8060ba6f175f71a7b57cb6d2061ce45a123" address="unix:///run/containerd/s/f26f95355db5ff382d4fa9ab84dcb0b4e4fa887bf09cffaae6939ce2744eeb97" protocol=ttrpc version=3 Sep 16 04:58:57.906549 systemd[1]: Started cri-containerd-91fa08f5f50606a3ad3c582388e8a8060ba6f175f71a7b57cb6d2061ce45a123.scope - libcontainer container 91fa08f5f50606a3ad3c582388e8a8060ba6f175f71a7b57cb6d2061ce45a123. Sep 16 04:58:57.954860 containerd[1567]: time="2025-09-16T04:58:57.954819268Z" level=info msg="StartContainer for \"91fa08f5f50606a3ad3c582388e8a8060ba6f175f71a7b57cb6d2061ce45a123\" returns successfully" Sep 16 04:58:58.270266 kubelet[1864]: E0916 04:58:58.270181 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:58:59.270447 kubelet[1864]: E0916 04:58:59.270382 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:00.255530 kubelet[1864]: E0916 04:59:00.255436 1864 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:00.271630 kubelet[1864]: E0916 04:59:00.271542 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:01.272389 kubelet[1864]: E0916 04:59:01.272317 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:02.272797 kubelet[1864]: E0916 04:59:02.272744 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:03.273461 kubelet[1864]: E0916 04:59:03.273391 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:04.023439 kubelet[1864]: I0916 04:59:04.023349 1864 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-28glz" podStartSLOduration=8.495009468 podStartE2EDuration="13.023332383s" podCreationTimestamp="2025-09-16 04:58:51 +0000 UTC" firstStartedPulling="2025-09-16 04:58:53.308754305 +0000 UTC m=+13.505555441" lastFinishedPulling="2025-09-16 04:58:57.837077217 +0000 UTC m=+18.033878356" observedRunningTime="2025-09-16 04:58:58.475963628 +0000 UTC m=+18.672764788" watchObservedRunningTime="2025-09-16 04:59:04.023332383 +0000 UTC m=+24.220133545" Sep 16 04:59:04.031261 systemd[1]: Created slice kubepods-besteffort-pod7e9a6965_0620_45f5_8b47_cc3692729ed9.slice - libcontainer container kubepods-besteffort-pod7e9a6965_0620_45f5_8b47_cc3692729ed9.slice. Sep 16 04:59:04.069004 kubelet[1864]: I0916 04:59:04.068840 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wl7f5\" (UniqueName: \"kubernetes.io/projected/7e9a6965-0620-45f5-8b47-cc3692729ed9-kube-api-access-wl7f5\") pod \"nfs-server-provisioner-0\" (UID: \"7e9a6965-0620-45f5-8b47-cc3692729ed9\") " pod="default/nfs-server-provisioner-0" Sep 16 04:59:04.069004 kubelet[1864]: I0916 04:59:04.068920 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/7e9a6965-0620-45f5-8b47-cc3692729ed9-data\") pod \"nfs-server-provisioner-0\" (UID: \"7e9a6965-0620-45f5-8b47-cc3692729ed9\") " pod="default/nfs-server-provisioner-0" Sep 16 04:59:04.274221 kubelet[1864]: E0916 04:59:04.273984 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:04.336682 containerd[1567]: time="2025-09-16T04:59:04.336622367Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:7e9a6965-0620-45f5-8b47-cc3692729ed9,Namespace:default,Attempt:0,}" Sep 16 04:59:04.356126 systemd-networkd[1448]: vethee8324cf: Link UP Sep 16 04:59:04.358287 kernel: cni0: port 2(vethee8324cf) entered blocking state Sep 16 04:59:04.358368 kernel: cni0: port 2(vethee8324cf) entered disabled state Sep 16 04:59:04.360559 kernel: vethee8324cf: entered allmulticast mode Sep 16 04:59:04.360852 kernel: vethee8324cf: entered promiscuous mode Sep 16 04:59:04.375638 kernel: cni0: port 2(vethee8324cf) entered blocking state Sep 16 04:59:04.375806 kernel: cni0: port 2(vethee8324cf) entered forwarding state Sep 16 04:59:04.375812 systemd-networkd[1448]: vethee8324cf: Gained carrier Sep 16 04:59:04.380773 containerd[1567]: 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)(0xc000022938), "name":"cbr0", "type":"bridge"} Sep 16 04:59:04.380773 containerd[1567]: delegateAdd: netconf sent to delegate plugin: Sep 16 04:59:04.422280 containerd[1567]: {"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-16T04:59:04.422209344Z" level=info msg="connecting to shim 50ba62c66a2bbc492d4ef6ce0c649c4d52f9ba904bba0cf9bb0c3828d93b2c0f" address="unix:///run/containerd/s/d9dbaf94178346bb39fc6d330868869f478af9af020d2b61ed1256f553f46975" namespace=k8s.io protocol=ttrpc version=3 Sep 16 04:59:04.466601 systemd[1]: Started cri-containerd-50ba62c66a2bbc492d4ef6ce0c649c4d52f9ba904bba0cf9bb0c3828d93b2c0f.scope - libcontainer container 50ba62c66a2bbc492d4ef6ce0c649c4d52f9ba904bba0cf9bb0c3828d93b2c0f. Sep 16 04:59:04.538692 containerd[1567]: time="2025-09-16T04:59:04.537708093Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:7e9a6965-0620-45f5-8b47-cc3692729ed9,Namespace:default,Attempt:0,} returns sandbox id \"50ba62c66a2bbc492d4ef6ce0c649c4d52f9ba904bba0cf9bb0c3828d93b2c0f\"" Sep 16 04:59:04.541020 containerd[1567]: time="2025-09-16T04:59:04.540953624Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 16 04:59:05.275287 kubelet[1864]: E0916 04:59:05.275185 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:06.165543 systemd-networkd[1448]: vethee8324cf: Gained IPv6LL Sep 16 04:59:06.276069 kubelet[1864]: E0916 04:59:06.276012 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:06.985339 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1284233281.mount: Deactivated successfully. Sep 16 04:59:07.277352 kubelet[1864]: E0916 04:59:07.276808 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:08.277433 kubelet[1864]: E0916 04:59:08.277340 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:09.278573 kubelet[1864]: E0916 04:59:09.278514 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:09.535299 containerd[1567]: time="2025-09-16T04:59:09.534967528Z" 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 16 04:59:09.536832 containerd[1567]: time="2025-09-16T04:59:09.536624155Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Sep 16 04:59:09.537416 containerd[1567]: time="2025-09-16T04:59:09.537377203Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:59:09.540392 containerd[1567]: time="2025-09-16T04:59:09.540343093Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:59:09.541737 containerd[1567]: time="2025-09-16T04:59:09.541567672Z" 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.000553243s" Sep 16 04:59:09.541737 containerd[1567]: time="2025-09-16T04:59:09.541618023Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Sep 16 04:59:09.545503 containerd[1567]: time="2025-09-16T04:59:09.545441725Z" level=info msg="CreateContainer within sandbox \"50ba62c66a2bbc492d4ef6ce0c649c4d52f9ba904bba0cf9bb0c3828d93b2c0f\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 16 04:59:09.555286 containerd[1567]: time="2025-09-16T04:59:09.554969812Z" level=info msg="Container d168760f365790d5b0ddca6d85e081182d60ea44b13cc1184cb82bd93df59768: CDI devices from CRI Config.CDIDevices: []" Sep 16 04:59:09.570439 containerd[1567]: time="2025-09-16T04:59:09.570289477Z" level=info msg="CreateContainer within sandbox \"50ba62c66a2bbc492d4ef6ce0c649c4d52f9ba904bba0cf9bb0c3828d93b2c0f\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"d168760f365790d5b0ddca6d85e081182d60ea44b13cc1184cb82bd93df59768\"" Sep 16 04:59:09.572305 containerd[1567]: time="2025-09-16T04:59:09.571334374Z" level=info msg="StartContainer for \"d168760f365790d5b0ddca6d85e081182d60ea44b13cc1184cb82bd93df59768\"" Sep 16 04:59:09.573313 containerd[1567]: time="2025-09-16T04:59:09.573275608Z" level=info msg="connecting to shim d168760f365790d5b0ddca6d85e081182d60ea44b13cc1184cb82bd93df59768" address="unix:///run/containerd/s/d9dbaf94178346bb39fc6d330868869f478af9af020d2b61ed1256f553f46975" protocol=ttrpc version=3 Sep 16 04:59:09.612529 systemd[1]: Started cri-containerd-d168760f365790d5b0ddca6d85e081182d60ea44b13cc1184cb82bd93df59768.scope - libcontainer container d168760f365790d5b0ddca6d85e081182d60ea44b13cc1184cb82bd93df59768. Sep 16 04:59:09.669076 containerd[1567]: time="2025-09-16T04:59:09.667988587Z" level=info msg="StartContainer for \"d168760f365790d5b0ddca6d85e081182d60ea44b13cc1184cb82bd93df59768\" returns successfully" Sep 16 04:59:10.279751 kubelet[1864]: E0916 04:59:10.279686 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:10.516652 kubelet[1864]: I0916 04:59:10.516575 1864 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.5138370540000001 podStartE2EDuration="6.516554867s" podCreationTimestamp="2025-09-16 04:59:04 +0000 UTC" firstStartedPulling="2025-09-16 04:59:04.540401621 +0000 UTC m=+24.737202770" lastFinishedPulling="2025-09-16 04:59:09.543119448 +0000 UTC m=+29.739920583" observedRunningTime="2025-09-16 04:59:10.516310285 +0000 UTC m=+30.713111453" watchObservedRunningTime="2025-09-16 04:59:10.516554867 +0000 UTC m=+30.713356041" Sep 16 04:59:11.279935 kubelet[1864]: E0916 04:59:11.279856 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:12.280151 kubelet[1864]: E0916 04:59:12.280065 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:13.280659 kubelet[1864]: E0916 04:59:13.280568 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:14.281148 kubelet[1864]: E0916 04:59:14.281052 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:15.281371 kubelet[1864]: E0916 04:59:15.281285 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:16.282414 kubelet[1864]: E0916 04:59:16.282357 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:17.282940 kubelet[1864]: E0916 04:59:17.282838 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:18.283616 kubelet[1864]: E0916 04:59:18.283529 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:18.773427 update_engine[1543]: I20250916 04:59:18.773341 1543 update_attempter.cc:509] Updating boot flags... Sep 16 04:59:19.284178 kubelet[1864]: E0916 04:59:19.284101 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:19.828153 systemd[1]: Created slice kubepods-besteffort-pod9561ad7b_3772_4dbc_89dd_2c38a63aa50d.slice - libcontainer container kubepods-besteffort-pod9561ad7b_3772_4dbc_89dd_2c38a63aa50d.slice. Sep 16 04:59:19.877579 kubelet[1864]: I0916 04:59:19.877511 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-08992f16-5563-4367-9ff5-d52df241c9ff\" (UniqueName: \"kubernetes.io/nfs/9561ad7b-3772-4dbc-89dd-2c38a63aa50d-pvc-08992f16-5563-4367-9ff5-d52df241c9ff\") pod \"test-pod-1\" (UID: \"9561ad7b-3772-4dbc-89dd-2c38a63aa50d\") " pod="default/test-pod-1" Sep 16 04:59:19.877579 kubelet[1864]: I0916 04:59:19.877571 1864 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-h5585\" (UniqueName: \"kubernetes.io/projected/9561ad7b-3772-4dbc-89dd-2c38a63aa50d-kube-api-access-h5585\") pod \"test-pod-1\" (UID: \"9561ad7b-3772-4dbc-89dd-2c38a63aa50d\") " pod="default/test-pod-1" Sep 16 04:59:20.029437 kernel: netfs: FS-Cache loaded Sep 16 04:59:20.103606 kernel: RPC: Registered named UNIX socket transport module. Sep 16 04:59:20.103766 kernel: RPC: Registered udp transport module. Sep 16 04:59:20.103799 kernel: RPC: Registered tcp transport module. Sep 16 04:59:20.103835 kernel: RPC: Registered tcp-with-tls transport module. Sep 16 04:59:20.104536 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 16 04:59:20.255339 kubelet[1864]: E0916 04:59:20.255238 1864 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:20.284366 kubelet[1864]: E0916 04:59:20.284288 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:20.357644 kernel: NFS: Registering the id_resolver key type Sep 16 04:59:20.357824 kernel: Key type id_resolver registered Sep 16 04:59:20.357857 kernel: Key type id_legacy registered Sep 16 04:59:20.395656 nfsidmap[2892]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-n-96e83010f3' Sep 16 04:59:20.401816 nfsidmap[2893]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '0.0-n-96e83010f3' Sep 16 04:59:20.418613 nfsrahead[2894]: setting /var/lib/kubelet/pods/9561ad7b-3772-4dbc-89dd-2c38a63aa50d/volumes/kubernetes.io~nfs/pvc-08992f16-5563-4367-9ff5-d52df241c9ff readahead to 128 Sep 16 04:59:20.432759 containerd[1567]: time="2025-09-16T04:59:20.432715550Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9561ad7b-3772-4dbc-89dd-2c38a63aa50d,Namespace:default,Attempt:0,}" Sep 16 04:59:20.464403 kernel: cni0: port 3(vethbe38736b) entered blocking state Sep 16 04:59:20.464520 kernel: cni0: port 3(vethbe38736b) entered disabled state Sep 16 04:59:20.462096 systemd-networkd[1448]: vethbe38736b: Link UP Sep 16 04:59:20.467456 kernel: vethbe38736b: entered allmulticast mode Sep 16 04:59:20.467569 kernel: vethbe38736b: entered promiscuous mode Sep 16 04:59:20.475396 kernel: cni0: port 3(vethbe38736b) entered blocking state Sep 16 04:59:20.475488 kernel: cni0: port 3(vethbe38736b) entered forwarding state Sep 16 04:59:20.476330 systemd-networkd[1448]: vethbe38736b: Gained carrier Sep 16 04:59:20.483288 containerd[1567]: 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 16 04:59:20.483288 containerd[1567]: delegateAdd: netconf sent to delegate plugin: Sep 16 04:59:20.544930 containerd[1567]: {"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-16T04:59:20.544835952Z" level=info msg="connecting to shim 9b094c6609eea2d4df0481d3a4f0ed72350b9759d40638276b9d841722a906ea" address="unix:///run/containerd/s/1d88db286ee3e32304970529d7bad71fad42ebc0e7eab4e6ecd9f4fd80ade818" namespace=k8s.io protocol=ttrpc version=3 Sep 16 04:59:20.618607 systemd[1]: Started cri-containerd-9b094c6609eea2d4df0481d3a4f0ed72350b9759d40638276b9d841722a906ea.scope - libcontainer container 9b094c6609eea2d4df0481d3a4f0ed72350b9759d40638276b9d841722a906ea. Sep 16 04:59:20.717440 containerd[1567]: time="2025-09-16T04:59:20.717368277Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9561ad7b-3772-4dbc-89dd-2c38a63aa50d,Namespace:default,Attempt:0,} returns sandbox id \"9b094c6609eea2d4df0481d3a4f0ed72350b9759d40638276b9d841722a906ea\"" Sep 16 04:59:20.720375 containerd[1567]: time="2025-09-16T04:59:20.720292629Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 16 04:59:21.060846 containerd[1567]: time="2025-09-16T04:59:21.060038536Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 16 04:59:21.060846 containerd[1567]: time="2025-09-16T04:59:21.060650602Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 16 04:59:21.064688 containerd[1567]: time="2025-09-16T04:59:21.064618743Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:3faf4e6888eeaa3974f71cfac4283b6b674433e2d0e91ef709d1a4a2643bd7cc\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:f58d36003a8cbcd81a7136799a0563e0c5c8de28ce4b3386dd52dc5370c4b893\", size \"73310322\" in 344.236156ms" Sep 16 04:59:21.064954 containerd[1567]: time="2025-09-16T04:59:21.064927683Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:3faf4e6888eeaa3974f71cfac4283b6b674433e2d0e91ef709d1a4a2643bd7cc\"" Sep 16 04:59:21.068056 containerd[1567]: time="2025-09-16T04:59:21.068010327Z" level=info msg="CreateContainer within sandbox \"9b094c6609eea2d4df0481d3a4f0ed72350b9759d40638276b9d841722a906ea\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 16 04:59:21.080315 containerd[1567]: time="2025-09-16T04:59:21.079408840Z" level=info msg="Container a1a4f791bd432a377da4727ac10628ac019afadf7a8f811f166279ef86f1c623: CDI devices from CRI Config.CDIDevices: []" Sep 16 04:59:21.098432 containerd[1567]: time="2025-09-16T04:59:21.098279568Z" level=info msg="CreateContainer within sandbox \"9b094c6609eea2d4df0481d3a4f0ed72350b9759d40638276b9d841722a906ea\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a1a4f791bd432a377da4727ac10628ac019afadf7a8f811f166279ef86f1c623\"" Sep 16 04:59:21.099187 containerd[1567]: time="2025-09-16T04:59:21.099138379Z" level=info msg="StartContainer for \"a1a4f791bd432a377da4727ac10628ac019afadf7a8f811f166279ef86f1c623\"" Sep 16 04:59:21.100446 containerd[1567]: time="2025-09-16T04:59:21.100404204Z" level=info msg="connecting to shim a1a4f791bd432a377da4727ac10628ac019afadf7a8f811f166279ef86f1c623" address="unix:///run/containerd/s/1d88db286ee3e32304970529d7bad71fad42ebc0e7eab4e6ecd9f4fd80ade818" protocol=ttrpc version=3 Sep 16 04:59:21.132576 systemd[1]: Started cri-containerd-a1a4f791bd432a377da4727ac10628ac019afadf7a8f811f166279ef86f1c623.scope - libcontainer container a1a4f791bd432a377da4727ac10628ac019afadf7a8f811f166279ef86f1c623. Sep 16 04:59:21.173985 containerd[1567]: time="2025-09-16T04:59:21.173934183Z" level=info msg="StartContainer for \"a1a4f791bd432a377da4727ac10628ac019afadf7a8f811f166279ef86f1c623\" returns successfully" Sep 16 04:59:21.285613 kubelet[1864]: E0916 04:59:21.285497 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:21.541850 kubelet[1864]: I0916 04:59:21.541769 1864 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.194765792 podStartE2EDuration="17.541750341s" podCreationTimestamp="2025-09-16 04:59:04 +0000 UTC" firstStartedPulling="2025-09-16 04:59:20.71933191 +0000 UTC m=+40.916133074" lastFinishedPulling="2025-09-16 04:59:21.066316487 +0000 UTC m=+41.263117623" observedRunningTime="2025-09-16 04:59:21.541678511 +0000 UTC m=+41.738479649" watchObservedRunningTime="2025-09-16 04:59:21.541750341 +0000 UTC m=+41.738551526" Sep 16 04:59:21.781533 systemd-networkd[1448]: vethbe38736b: Gained IPv6LL Sep 16 04:59:22.286435 kubelet[1864]: E0916 04:59:22.286371 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:23.286705 kubelet[1864]: E0916 04:59:23.286639 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:24.287823 kubelet[1864]: E0916 04:59:24.287747 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:25.288438 kubelet[1864]: E0916 04:59:25.288357 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:26.288824 kubelet[1864]: E0916 04:59:26.288745 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:27.289959 kubelet[1864]: E0916 04:59:27.289890 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 16 04:59:28.290552 kubelet[1864]: E0916 04:59:28.290486 1864 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"