Sep 8 00:39:46.995745 kernel: Linux version 6.6.156-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Mon Sep 7 22:41:00 -00 2026 Sep 8 00:39:46.995950 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=a3f2723083b2234d735715f7c003aade430032e93573e34ec6036fa6f67473bd Sep 8 00:39:46.996021 kernel: BIOS-provided physical RAM map: Sep 8 00:39:46.996030 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 8 00:39:46.996038 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 8 00:39:46.996045 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 8 00:39:46.996054 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Sep 8 00:39:46.996063 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Sep 8 00:39:46.996070 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 8 00:39:46.996078 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Sep 8 00:39:46.996088 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 8 00:39:46.996096 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 8 00:39:46.996103 kernel: NX (Execute Disable) protection: active Sep 8 00:39:46.996111 kernel: APIC: Static calls initialized Sep 8 00:39:46.996120 kernel: SMBIOS 2.8 present. Sep 8 00:39:46.996128 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Sep 8 00:39:46.996139 kernel: Hypervisor detected: KVM Sep 8 00:39:46.996148 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 8 00:39:46.996157 kernel: kvm-clock: using sched offset of 3750628836 cycles Sep 8 00:39:46.996166 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 8 00:39:46.996174 kernel: tsc: Detected 2793.438 MHz processor Sep 8 00:39:46.996183 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 8 00:39:46.996192 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 8 00:39:46.996200 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x10000000000 Sep 8 00:39:46.996208 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 8 00:39:46.996220 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 8 00:39:46.996228 kernel: Using GB pages for direct mapping Sep 8 00:39:46.996237 kernel: ACPI: Early table checksum verification disabled Sep 8 00:39:46.996245 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Sep 8 00:39:46.996254 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 00:39:46.996261 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 00:39:46.996269 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 00:39:46.996277 kernel: ACPI: FACS 0x000000009CFE0000 000040 Sep 8 00:39:46.996284 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 00:39:46.996294 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 00:39:46.996301 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 00:39:46.996308 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 8 00:39:46.996316 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Sep 8 00:39:46.996323 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Sep 8 00:39:46.996331 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Sep 8 00:39:46.996342 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Sep 8 00:39:46.996351 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Sep 8 00:39:46.996358 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Sep 8 00:39:46.996367 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Sep 8 00:39:46.996375 kernel: No NUMA configuration found Sep 8 00:39:46.996383 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Sep 8 00:39:46.996390 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] Sep 8 00:39:46.996398 kernel: Zone ranges: Sep 8 00:39:46.996407 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 8 00:39:46.996416 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Sep 8 00:39:46.996424 kernel: Normal empty Sep 8 00:39:46.996431 kernel: Movable zone start for each node Sep 8 00:39:46.996439 kernel: Early memory node ranges Sep 8 00:39:46.996446 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 8 00:39:46.996454 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Sep 8 00:39:46.996462 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Sep 8 00:39:46.996470 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 8 00:39:46.996480 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 8 00:39:46.996488 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Sep 8 00:39:46.996495 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 8 00:39:46.996503 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 8 00:39:46.996511 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 8 00:39:46.996520 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 8 00:39:46.996527 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 8 00:39:46.996535 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 8 00:39:46.996543 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 8 00:39:46.996553 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 8 00:39:46.996561 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 8 00:39:46.996568 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 8 00:39:46.996576 kernel: TSC deadline timer available Sep 8 00:39:46.996604 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Sep 8 00:39:46.996612 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 8 00:39:46.996620 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 8 00:39:46.996627 kernel: kvm-guest: setup PV sched yield Sep 8 00:39:46.996635 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Sep 8 00:39:46.996643 kernel: Booting paravirtualized kernel on KVM Sep 8 00:39:46.996654 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 8 00:39:46.996662 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 8 00:39:46.996670 kernel: percpu: Embedded 57 pages/cpu s196392 r8192 d28888 u524288 Sep 8 00:39:46.996677 kernel: pcpu-alloc: s196392 r8192 d28888 u524288 alloc=1*2097152 Sep 8 00:39:46.996685 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 8 00:39:46.996692 kernel: kvm-guest: PV spinlocks enabled Sep 8 00:39:46.996699 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 8 00:39:46.996708 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=a3f2723083b2234d735715f7c003aade430032e93573e34ec6036fa6f67473bd Sep 8 00:39:46.996718 kernel: random: crng init done Sep 8 00:39:46.996725 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 8 00:39:46.996733 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 8 00:39:46.996741 kernel: Fallback order for Node 0: 0 Sep 8 00:39:46.996748 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 Sep 8 00:39:46.996755 kernel: Policy zone: DMA32 Sep 8 00:39:46.996762 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 8 00:39:46.996770 kernel: Memory: 2431604K/2571752K available (14336K kernel code, 2288K rwdata, 22772K rodata, 42920K init, 2236K bss, 139944K reserved, 0K cma-reserved) Sep 8 00:39:46.996778 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 8 00:39:46.996787 kernel: ftrace: allocating 37502 entries in 148 pages Sep 8 00:39:46.996795 kernel: ftrace: allocated 148 pages with 3 groups Sep 8 00:39:46.996802 kernel: Dynamic Preempt: voluntary Sep 8 00:39:46.996810 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 8 00:39:46.996818 kernel: rcu: RCU event tracing is enabled. Sep 8 00:39:46.996826 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 8 00:39:46.996833 kernel: Trampoline variant of Tasks RCU enabled. Sep 8 00:39:46.996841 kernel: Rude variant of Tasks RCU enabled. Sep 8 00:39:46.996848 kernel: Tracing variant of Tasks RCU enabled. Sep 8 00:39:46.996858 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 8 00:39:46.996866 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 8 00:39:46.997061 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 8 00:39:46.997193 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 8 00:39:46.997203 kernel: Console: colour VGA+ 80x25 Sep 8 00:39:46.997212 kernel: printk: console [ttyS0] enabled Sep 8 00:39:46.997220 kernel: ACPI: Core revision 20230628 Sep 8 00:39:46.997229 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 8 00:39:46.997237 kernel: APIC: Switch to symmetric I/O mode setup Sep 8 00:39:46.997284 kernel: x2apic enabled Sep 8 00:39:46.997307 kernel: APIC: Switched APIC routing to: physical x2apic Sep 8 00:39:46.997316 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 8 00:39:46.997327 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 8 00:39:46.997335 kernel: kvm-guest: setup PV IPIs Sep 8 00:39:46.997343 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 8 00:39:46.997351 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 8 00:39:46.997371 kernel: Calibrating delay loop (skipped) preset value.. 5586.87 BogoMIPS (lpj=2793438) Sep 8 00:39:46.997380 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 8 00:39:46.997389 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Sep 8 00:39:46.997397 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Sep 8 00:39:46.997406 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 8 00:39:46.997418 kernel: Spectre V2 : Mitigation: Retpolines Sep 8 00:39:46.997428 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 8 00:39:46.997438 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Sep 8 00:39:46.997448 kernel: RETBleed: Vulnerable Sep 8 00:39:46.997460 kernel: Speculative Store Bypass: Vulnerable Sep 8 00:39:46.997469 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 8 00:39:46.997478 kernel: GDS: Unknown: Dependent on hypervisor status Sep 8 00:39:46.997487 kernel: active return thunk: its_return_thunk Sep 8 00:39:46.997496 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 8 00:39:46.997506 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 8 00:39:46.997516 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 8 00:39:46.997526 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 8 00:39:46.997536 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Sep 8 00:39:46.997549 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Sep 8 00:39:46.997559 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Sep 8 00:39:46.997570 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 8 00:39:46.997580 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Sep 8 00:39:46.997590 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Sep 8 00:39:46.997600 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Sep 8 00:39:46.997610 kernel: x86/fpu: Enabled xstate features 0xe7, context size is 2432 bytes, using 'compacted' format. Sep 8 00:39:46.997620 kernel: Freeing SMP alternatives memory: 32K Sep 8 00:39:46.997629 kernel: pid_max: default: 32768 minimum: 301 Sep 8 00:39:46.997642 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 8 00:39:46.997652 kernel: landlock: Up and running. Sep 8 00:39:46.997662 kernel: SELinux: Initializing. Sep 8 00:39:46.997672 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 8 00:39:46.997682 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 8 00:39:46.997692 kernel: smpboot: CPU0: Intel(R) Xeon(R) Platinum 8370C CPU @ 2.80GHz (family: 0x6, model: 0x6a, stepping: 0x6) Sep 8 00:39:46.997703 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 8 00:39:46.997713 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 8 00:39:46.997724 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 8 00:39:46.997797 kernel: Performance Events: unsupported p6 CPU model 106 no PMU driver, software events only. Sep 8 00:39:46.997863 kernel: signal: max sigframe size: 3632 Sep 8 00:39:46.997982 kernel: rcu: Hierarchical SRCU implementation. Sep 8 00:39:46.998043 kernel: rcu: Max phase no-delay instances is 400. Sep 8 00:39:46.998055 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 8 00:39:46.998064 kernel: smp: Bringing up secondary CPUs ... Sep 8 00:39:46.998074 kernel: smpboot: x86: Booting SMP configuration: Sep 8 00:39:46.998084 kernel: .... node #0, CPUs: #1 #2 #3 Sep 8 00:39:46.998094 kernel: smp: Brought up 1 node, 4 CPUs Sep 8 00:39:46.998111 kernel: smpboot: Max logical packages: 1 Sep 8 00:39:46.998122 kernel: smpboot: Total of 4 processors activated (22347.50 BogoMIPS) Sep 8 00:39:46.998133 kernel: devtmpfs: initialized Sep 8 00:39:46.998143 kernel: x86/mm: Memory block size: 128MB Sep 8 00:39:46.998153 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 8 00:39:46.998165 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 8 00:39:46.998177 kernel: pinctrl core: initialized pinctrl subsystem Sep 8 00:39:46.998189 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 8 00:39:46.998216 kernel: audit: initializing netlink subsys (disabled) Sep 8 00:39:46.998239 kernel: audit: type=2000 audit(1788827986.250:1): state=initialized audit_enabled=0 res=1 Sep 8 00:39:46.998249 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 8 00:39:46.998259 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 8 00:39:46.998268 kernel: cpuidle: using governor menu Sep 8 00:39:46.998279 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 8 00:39:46.998288 kernel: dca service started, version 1.12.1 Sep 8 00:39:46.998297 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Sep 8 00:39:46.998306 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 8 00:39:46.998315 kernel: PCI: Using configuration type 1 for base access Sep 8 00:39:46.998327 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 8 00:39:46.998337 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 8 00:39:46.998346 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 8 00:39:46.998356 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 8 00:39:46.998366 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 8 00:39:46.998377 kernel: ACPI: Added _OSI(Module Device) Sep 8 00:39:46.998387 kernel: ACPI: Added _OSI(Processor Device) Sep 8 00:39:46.998398 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 8 00:39:46.998408 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 8 00:39:46.998420 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 8 00:39:46.998430 kernel: ACPI: Interpreter enabled Sep 8 00:39:46.998440 kernel: ACPI: PM: (supports S0 S3 S5) Sep 8 00:39:46.998552 kernel: ACPI: Using IOAPIC for interrupt routing Sep 8 00:39:46.999022 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 8 00:39:46.999034 kernel: PCI: Using E820 reservations for host bridge windows Sep 8 00:39:46.999044 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 8 00:39:46.999055 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 8 00:39:46.999636 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 8 00:39:46.999788 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 8 00:39:46.999926 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 8 00:39:46.999940 kernel: PCI host bridge to bus 0000:00 Sep 8 00:39:47.000029 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 8 00:39:47.000099 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 8 00:39:47.000166 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 8 00:39:47.000238 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 8 00:39:47.000306 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 8 00:39:47.000372 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Sep 8 00:39:47.000440 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 8 00:39:47.000531 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Sep 8 00:39:47.000617 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Sep 8 00:39:47.000689 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Sep 8 00:39:47.000769 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Sep 8 00:39:47.000849 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Sep 8 00:39:47.001312 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 8 00:39:47.001495 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Sep 8 00:39:47.001579 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Sep 8 00:39:47.001659 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Sep 8 00:39:47.001719 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Sep 8 00:39:47.001801 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Sep 8 00:39:47.001942 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Sep 8 00:39:47.002049 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Sep 8 00:39:47.002139 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Sep 8 00:39:47.002244 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Sep 8 00:39:47.002327 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Sep 8 00:39:47.002395 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Sep 8 00:39:47.002490 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Sep 8 00:39:47.002560 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Sep 8 00:39:47.002657 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Sep 8 00:39:47.002779 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 8 00:39:47.002924 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Sep 8 00:39:47.003034 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Sep 8 00:39:47.003102 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Sep 8 00:39:47.003412 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Sep 8 00:39:47.003476 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Sep 8 00:39:47.003483 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 8 00:39:47.003490 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 8 00:39:47.003496 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 8 00:39:47.003502 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 8 00:39:47.003508 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 8 00:39:47.003517 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 8 00:39:47.003523 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 8 00:39:47.003529 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 8 00:39:47.003534 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 8 00:39:47.003540 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 8 00:39:47.003546 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 8 00:39:47.003552 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 8 00:39:47.003558 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 8 00:39:47.003564 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 8 00:39:47.003571 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 8 00:39:47.003576 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 8 00:39:47.003582 kernel: iommu: Default domain type: Translated Sep 8 00:39:47.003588 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 8 00:39:47.003594 kernel: PCI: Using ACPI for IRQ routing Sep 8 00:39:47.003600 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 8 00:39:47.003606 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 8 00:39:47.003612 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Sep 8 00:39:47.003670 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 8 00:39:47.003729 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 8 00:39:47.003821 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 8 00:39:47.003829 kernel: vgaarb: loaded Sep 8 00:39:47.003835 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 8 00:39:47.003842 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 8 00:39:47.003854 kernel: clocksource: Switched to clocksource kvm-clock Sep 8 00:39:47.003865 kernel: VFS: Disk quotas dquot_6.6.0 Sep 8 00:39:47.004155 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 8 00:39:47.004174 kernel: pnp: PnP ACPI init Sep 8 00:39:47.004298 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 8 00:39:47.004313 kernel: pnp: PnP ACPI: found 6 devices Sep 8 00:39:47.004324 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 8 00:39:47.004333 kernel: NET: Registered PF_INET protocol family Sep 8 00:39:47.004345 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 8 00:39:47.004356 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 8 00:39:47.004368 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 8 00:39:47.004383 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 8 00:39:47.004394 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 8 00:39:47.004405 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 8 00:39:47.004411 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 8 00:39:47.004417 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 8 00:39:47.004422 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 8 00:39:47.004428 kernel: NET: Registered PF_XDP protocol family Sep 8 00:39:47.004491 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 8 00:39:47.004544 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 8 00:39:47.004596 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 8 00:39:47.004645 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 8 00:39:47.004694 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 8 00:39:47.004751 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Sep 8 00:39:47.004764 kernel: PCI: CLS 0 bytes, default 64 Sep 8 00:39:47.004776 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 8 00:39:47.004788 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x284409db922, max_idle_ns: 440795228871 ns Sep 8 00:39:47.004799 kernel: Initialise system trusted keyrings Sep 8 00:39:47.004811 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 8 00:39:47.004816 kernel: Key type asymmetric registered Sep 8 00:39:47.004826 kernel: Asymmetric key parser 'x509' registered Sep 8 00:39:47.004836 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 8 00:39:47.004848 kernel: io scheduler mq-deadline registered Sep 8 00:39:47.004859 kernel: io scheduler kyber registered Sep 8 00:39:47.004870 kernel: io scheduler bfq registered Sep 8 00:39:47.005173 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 8 00:39:47.005182 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 8 00:39:47.005188 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 8 00:39:47.005197 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 8 00:39:47.005204 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 8 00:39:47.005209 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 8 00:39:47.005216 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 8 00:39:47.005222 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 8 00:39:47.005227 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 8 00:39:47.005313 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 8 00:39:47.005322 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 8 00:39:47.005377 kernel: rtc_cmos 00:04: registered as rtc0 Sep 8 00:39:47.005428 kernel: rtc_cmos 00:04: setting system clock to 2026-09-08T00:39:46 UTC (1788827986) Sep 8 00:39:47.005478 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 8 00:39:47.005486 kernel: intel_pstate: CPU model not supported Sep 8 00:39:47.005491 kernel: NET: Registered PF_INET6 protocol family Sep 8 00:39:47.005497 kernel: Segment Routing with IPv6 Sep 8 00:39:47.005503 kernel: In-situ OAM (IOAM) with IPv6 Sep 8 00:39:47.005509 kernel: NET: Registered PF_PACKET protocol family Sep 8 00:39:47.005517 kernel: Key type dns_resolver registered Sep 8 00:39:47.005522 kernel: IPI shorthand broadcast: enabled Sep 8 00:39:47.005528 kernel: sched_clock: Marking stable (851192015, 204135767)->(1119949493, -64621711) Sep 8 00:39:47.005535 kernel: registered taskstats version 1 Sep 8 00:39:47.005540 kernel: Loading compiled-in X.509 certificates Sep 8 00:39:47.005546 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.156-flatcar: 38c5ab60f1f6a1c80668a8bdc02d575ba7bd5359' Sep 8 00:39:47.005552 kernel: Key type .fscrypt registered Sep 8 00:39:47.005558 kernel: Key type fscrypt-provisioning registered Sep 8 00:39:47.005563 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 8 00:39:47.005569 kernel: ima: Allocated hash algorithm: sha1 Sep 8 00:39:47.005577 kernel: ima: No architecture policies found Sep 8 00:39:47.005583 kernel: clk: Disabling unused clocks Sep 8 00:39:47.005589 kernel: Freeing unused kernel image (initmem) memory: 42920K Sep 8 00:39:47.005594 kernel: Write protecting the kernel read-only data: 38912k Sep 8 00:39:47.005600 kernel: Freeing unused kernel image (rodata/data gap) memory: 1804K Sep 8 00:39:47.005606 kernel: Run /init as init process Sep 8 00:39:47.005611 kernel: with arguments: Sep 8 00:39:47.005617 kernel: /init Sep 8 00:39:47.005623 kernel: with environment: Sep 8 00:39:47.005631 kernel: HOME=/ Sep 8 00:39:47.005641 kernel: TERM=linux Sep 8 00:39:47.005652 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 8 00:39:47.005666 systemd[1]: Detected virtualization kvm. Sep 8 00:39:47.005676 systemd[1]: Detected architecture x86-64. Sep 8 00:39:47.005686 systemd[1]: Running in initrd. Sep 8 00:39:47.005695 systemd[1]: No hostname configured, using default hostname. Sep 8 00:39:47.005704 systemd[1]: Hostname set to . Sep 8 00:39:47.005716 systemd[1]: Initializing machine ID from VM UUID. Sep 8 00:39:47.005726 systemd[1]: Queued start job for default target initrd.target. Sep 8 00:39:47.005736 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 8 00:39:47.005745 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 8 00:39:47.005757 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 8 00:39:47.005767 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 8 00:39:47.005777 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 8 00:39:47.005790 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 8 00:39:47.005817 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 8 00:39:47.005827 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 8 00:39:47.005838 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 8 00:39:47.005849 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 8 00:39:47.005861 systemd[1]: Reached target paths.target - Path Units. Sep 8 00:39:47.005872 systemd[1]: Reached target slices.target - Slice Units. Sep 8 00:39:47.006104 systemd[1]: Reached target swap.target - Swaps. Sep 8 00:39:47.006111 systemd[1]: Reached target timers.target - Timer Units. Sep 8 00:39:47.006117 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 8 00:39:47.006123 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 8 00:39:47.006130 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 8 00:39:47.006136 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 8 00:39:47.006142 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 8 00:39:47.006152 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 8 00:39:47.006158 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 8 00:39:47.006164 systemd[1]: Reached target sockets.target - Socket Units. Sep 8 00:39:47.006171 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 8 00:39:47.006177 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 8 00:39:47.006183 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 8 00:39:47.006190 systemd[1]: Starting systemd-fsck-usr.service... Sep 8 00:39:47.006196 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 8 00:39:47.006204 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 8 00:39:47.006210 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 00:39:47.006216 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 8 00:39:47.006222 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 8 00:39:47.006228 systemd[1]: Finished systemd-fsck-usr.service. Sep 8 00:39:47.006260 systemd-journald[193]: Collecting audit messages is disabled. Sep 8 00:39:47.006281 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 8 00:39:47.006289 systemd-journald[193]: Journal started Sep 8 00:39:47.006304 systemd-journald[193]: Runtime Journal (/run/log/journal/bcb1d9aada7244149f520e1d0f6d1158) is 6.0M, max 48.3M, 42.3M free. Sep 8 00:39:46.999710 systemd-modules-load[194]: Inserted module 'overlay' Sep 8 00:39:47.009481 systemd[1]: Started systemd-journald.service - Journal Service. Sep 8 00:39:47.011172 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 8 00:39:47.016056 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 8 00:39:47.019473 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 8 00:39:47.035788 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 8 00:39:47.117459 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 8 00:39:47.117498 kernel: Bridge firewalling registered Sep 8 00:39:47.039149 systemd-modules-load[194]: Inserted module 'br_netfilter' Sep 8 00:39:47.120716 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 8 00:39:47.122906 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 00:39:47.128707 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 8 00:39:47.142582 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 8 00:39:47.148366 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 8 00:39:47.159749 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 8 00:39:47.162196 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 8 00:39:47.169077 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 8 00:39:47.174320 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 8 00:39:47.185201 dracut-cmdline[228]: dracut-dracut-053 Sep 8 00:39:47.189870 dracut-cmdline[228]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=a3f2723083b2234d735715f7c003aade430032e93573e34ec6036fa6f67473bd Sep 8 00:39:47.233151 systemd-resolved[230]: Positive Trust Anchors: Sep 8 00:39:47.233189 systemd-resolved[230]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 8 00:39:47.233225 systemd-resolved[230]: 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 8 00:39:47.237111 systemd-resolved[230]: Defaulting to hostname 'linux'. Sep 8 00:39:47.238949 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 8 00:39:47.240715 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 8 00:39:47.326024 kernel: SCSI subsystem initialized Sep 8 00:39:47.336955 kernel: Loading iSCSI transport class v2.0-870. Sep 8 00:39:47.349007 kernel: iscsi: registered transport (tcp) Sep 8 00:39:47.369018 kernel: iscsi: registered transport (qla4xxx) Sep 8 00:39:47.369423 kernel: QLogic iSCSI HBA Driver Sep 8 00:39:47.417276 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 8 00:39:47.437445 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 8 00:39:47.470981 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 8 00:39:47.471421 kernel: device-mapper: uevent: version 1.0.3 Sep 8 00:39:47.471458 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 8 00:39:47.521016 kernel: raid6: avx512x4 gen() 37259 MB/s Sep 8 00:39:47.538036 kernel: raid6: avx512x2 gen() 39480 MB/s Sep 8 00:39:47.554962 kernel: raid6: avx512x1 gen() 37891 MB/s Sep 8 00:39:47.572129 kernel: raid6: avx2x4 gen() 33382 MB/s Sep 8 00:39:47.589027 kernel: raid6: avx2x2 gen() 32462 MB/s Sep 8 00:39:47.607126 kernel: raid6: avx2x1 gen() 22828 MB/s Sep 8 00:39:47.607525 kernel: raid6: using algorithm avx512x2 gen() 39480 MB/s Sep 8 00:39:47.626333 kernel: raid6: .... xor() 25051 MB/s, rmw enabled Sep 8 00:39:47.626725 kernel: raid6: using avx512x2 recovery algorithm Sep 8 00:39:47.654040 kernel: xor: automatically using best checksumming function avx Sep 8 00:39:47.800986 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 8 00:39:47.814569 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 8 00:39:47.829414 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 8 00:39:47.847061 systemd-udevd[415]: Using default interface naming scheme 'v255'. Sep 8 00:39:47.852624 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 8 00:39:47.860232 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 8 00:39:47.881723 dracut-pre-trigger[417]: rd.md=0: removing MD RAID activation Sep 8 00:39:47.923978 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 8 00:39:47.944387 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 8 00:39:47.997568 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 8 00:39:48.009207 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 8 00:39:48.027285 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 8 00:39:48.033527 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 8 00:39:48.042018 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 8 00:39:48.049305 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 8 00:39:48.052425 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 8 00:39:48.066002 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 8 00:39:48.066186 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 8 00:39:48.074061 kernel: cryptd: max_cpu_qlen set to 1000 Sep 8 00:39:48.074669 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 8 00:39:48.084710 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 8 00:39:48.084733 kernel: GPT:9289727 != 19775487 Sep 8 00:39:48.084742 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 8 00:39:48.084750 kernel: GPT:9289727 != 19775487 Sep 8 00:39:48.084766 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 8 00:39:48.084774 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 8 00:39:48.084783 kernel: libata version 3.00 loaded. Sep 8 00:39:48.074774 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 8 00:39:48.090001 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 8 00:39:48.092593 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 8 00:39:48.098934 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 00:39:48.100512 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 00:39:48.117014 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (459) Sep 8 00:39:48.120019 kernel: ahci 0000:00:1f.2: version 3.0 Sep 8 00:39:48.120264 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 8 00:39:48.122763 kernel: AVX2 version of gcm_enc/dec engaged. Sep 8 00:39:48.122766 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 00:39:48.126131 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 8 00:39:48.138151 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Sep 8 00:39:48.138570 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 8 00:39:48.138666 kernel: BTRFS: device fsid 18d846fb-6501-46de-8fd7-fa21fae087be devid 1 transid 31 /dev/vda3 scanned by (udev-worker) (462) Sep 8 00:39:48.141045 kernel: scsi host0: ahci Sep 8 00:39:48.141252 kernel: AES CTR mode by8 optimization enabled Sep 8 00:39:48.142961 kernel: scsi host1: ahci Sep 8 00:39:48.143232 kernel: scsi host2: ahci Sep 8 00:39:48.144912 kernel: scsi host3: ahci Sep 8 00:39:48.147150 kernel: scsi host4: ahci Sep 8 00:39:48.149027 kernel: scsi host5: ahci Sep 8 00:39:48.153123 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 Sep 8 00:39:48.153249 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 Sep 8 00:39:48.153264 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 Sep 8 00:39:48.154704 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 Sep 8 00:39:48.157195 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 Sep 8 00:39:48.157492 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 Sep 8 00:39:48.170198 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 8 00:39:48.278605 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 00:39:48.291399 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 8 00:39:48.337525 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 8 00:39:48.345252 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 8 00:39:48.346769 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 8 00:39:48.368494 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 8 00:39:48.374221 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 8 00:39:48.384118 disk-uuid[555]: Primary Header is updated. Sep 8 00:39:48.384118 disk-uuid[555]: Secondary Entries is updated. Sep 8 00:39:48.384118 disk-uuid[555]: Secondary Header is updated. Sep 8 00:39:48.394061 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 8 00:39:48.400011 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 8 00:39:48.420274 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 8 00:39:48.469009 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 8 00:39:48.469188 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 8 00:39:48.470987 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 8 00:39:48.472970 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 8 00:39:48.473218 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 8 00:39:48.476085 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 8 00:39:48.476229 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 8 00:39:48.477116 kernel: ata3.00: applying bridge limits Sep 8 00:39:48.478038 kernel: ata3.00: configured for UDMA/100 Sep 8 00:39:48.480620 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 8 00:39:48.531976 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 8 00:39:48.565951 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 8 00:39:48.579003 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 8 00:39:49.403072 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 8 00:39:49.403817 disk-uuid[556]: The operation has completed successfully. Sep 8 00:39:49.430287 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 8 00:39:49.430440 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 8 00:39:49.468656 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 8 00:39:49.473575 sh[593]: Success Sep 8 00:39:49.490979 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Sep 8 00:39:49.538296 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 8 00:39:49.551220 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 8 00:39:49.555866 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 8 00:39:49.568154 kernel: BTRFS info (device dm-0): first mount of filesystem 18d846fb-6501-46de-8fd7-fa21fae087be Sep 8 00:39:49.568530 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 8 00:39:49.568665 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 8 00:39:49.570470 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 8 00:39:49.571559 kernel: BTRFS info (device dm-0): using free space tree Sep 8 00:39:49.580684 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 8 00:39:49.584074 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 8 00:39:49.595330 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 8 00:39:49.598857 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 8 00:39:49.610094 kernel: BTRFS info (device vda6): first mount of filesystem 12df91fc-3dfb-4662-9114-c5aeff8c3bed Sep 8 00:39:49.610338 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 8 00:39:49.610358 kernel: BTRFS info (device vda6): using free space tree Sep 8 00:39:49.613997 kernel: BTRFS info (device vda6): auto enabling async discard Sep 8 00:39:49.626720 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 8 00:39:49.630576 kernel: BTRFS info (device vda6): last unmount of filesystem 12df91fc-3dfb-4662-9114-c5aeff8c3bed Sep 8 00:39:49.638329 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 8 00:39:49.647318 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 8 00:39:49.715638 ignition[679]: Ignition 2.19.0 Sep 8 00:39:49.715666 ignition[679]: Stage: fetch-offline Sep 8 00:39:49.715705 ignition[679]: no configs at "/usr/lib/ignition/base.d" Sep 8 00:39:49.715715 ignition[679]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 00:39:49.715814 ignition[679]: parsed url from cmdline: "" Sep 8 00:39:49.715818 ignition[679]: no config URL provided Sep 8 00:39:49.715824 ignition[679]: reading system config file "/usr/lib/ignition/user.ign" Sep 8 00:39:49.715831 ignition[679]: no config at "/usr/lib/ignition/user.ign" Sep 8 00:39:49.715862 ignition[679]: op(1): [started] loading QEMU firmware config module Sep 8 00:39:49.715867 ignition[679]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 8 00:39:49.735261 ignition[679]: op(1): [finished] loading QEMU firmware config module Sep 8 00:39:49.735972 ignition[679]: parsing config with SHA512: 2666a6bb381ec0cf10632f7526b24d9dbc5ac9eb968128e5a4da5d3c3e7294431c54705b742403daa6c6745b76940efc1879ce4f37aac1c4e6717ce6038bb2d2 Sep 8 00:39:49.739035 unknown[679]: fetched base config from "system" Sep 8 00:39:49.739107 unknown[679]: fetched user config from "qemu" Sep 8 00:39:49.739449 ignition[679]: fetch-offline: fetch-offline passed Sep 8 00:39:49.741408 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 8 00:39:49.739532 ignition[679]: Ignition finished successfully Sep 8 00:39:49.756016 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 8 00:39:49.777710 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 8 00:39:49.807831 systemd-networkd[782]: lo: Link UP Sep 8 00:39:49.807864 systemd-networkd[782]: lo: Gained carrier Sep 8 00:39:49.809282 systemd-networkd[782]: Enumeration completed Sep 8 00:39:49.810314 systemd-networkd[782]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 00:39:49.810318 systemd-networkd[782]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 8 00:39:49.811586 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 8 00:39:49.812005 systemd-networkd[782]: eth0: Link UP Sep 8 00:39:49.812009 systemd-networkd[782]: eth0: Gained carrier Sep 8 00:39:49.812018 systemd-networkd[782]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 00:39:49.814089 systemd[1]: Reached target network.target - Network. Sep 8 00:39:49.814695 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 8 00:39:49.833314 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 8 00:39:49.847414 systemd-networkd[782]: eth0: DHCPv4 address 10.0.0.145/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 8 00:39:49.849341 ignition[784]: Ignition 2.19.0 Sep 8 00:39:49.849403 ignition[784]: Stage: kargs Sep 8 00:39:49.849548 ignition[784]: no configs at "/usr/lib/ignition/base.d" Sep 8 00:39:49.853534 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 8 00:39:49.849555 ignition[784]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 00:39:49.850110 ignition[784]: kargs: kargs passed Sep 8 00:39:49.850141 ignition[784]: Ignition finished successfully Sep 8 00:39:49.866452 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 8 00:39:49.884666 ignition[793]: Ignition 2.19.0 Sep 8 00:39:49.884693 ignition[793]: Stage: disks Sep 8 00:39:49.885062 ignition[793]: no configs at "/usr/lib/ignition/base.d" Sep 8 00:39:49.885079 ignition[793]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 00:39:49.886085 ignition[793]: disks: disks passed Sep 8 00:39:49.892435 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 8 00:39:49.886178 ignition[793]: Ignition finished successfully Sep 8 00:39:49.897172 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 8 00:39:49.901527 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 8 00:39:49.905603 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 8 00:39:49.909448 systemd[1]: Reached target sysinit.target - System Initialization. Sep 8 00:39:49.913770 systemd[1]: Reached target basic.target - Basic System. Sep 8 00:39:49.926446 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 8 00:39:49.946653 systemd-fsck[802]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 8 00:39:49.957142 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 8 00:39:49.978559 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 8 00:39:50.098123 kernel: EXT4-fs (vda9): mounted filesystem 637735be-d7f6-41ad-bcf1-707e4ae6815d r/w with ordered data mode. Quota mode: none. Sep 8 00:39:50.098697 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 8 00:39:50.100539 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 8 00:39:50.117344 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 8 00:39:50.122238 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 8 00:39:50.124450 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 8 00:39:50.136835 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (810) Sep 8 00:39:50.137103 kernel: BTRFS info (device vda6): first mount of filesystem 12df91fc-3dfb-4662-9114-c5aeff8c3bed Sep 8 00:39:50.124672 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 8 00:39:50.149970 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 8 00:39:50.150223 kernel: BTRFS info (device vda6): using free space tree Sep 8 00:39:50.150243 kernel: BTRFS info (device vda6): auto enabling async discard Sep 8 00:39:50.125096 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 8 00:39:50.154272 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 8 00:39:50.158530 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 8 00:39:50.163013 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 8 00:39:50.218958 initrd-setup-root[835]: cut: /sysroot/etc/passwd: No such file or directory Sep 8 00:39:50.225038 initrd-setup-root[842]: cut: /sysroot/etc/group: No such file or directory Sep 8 00:39:50.231536 initrd-setup-root[849]: cut: /sysroot/etc/shadow: No such file or directory Sep 8 00:39:50.237772 initrd-setup-root[856]: cut: /sysroot/etc/gshadow: No such file or directory Sep 8 00:39:50.404457 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 8 00:39:50.416344 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 8 00:39:50.419279 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 8 00:39:50.432049 kernel: BTRFS info (device vda6): last unmount of filesystem 12df91fc-3dfb-4662-9114-c5aeff8c3bed Sep 8 00:39:50.451634 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 8 00:39:50.469622 ignition[925]: INFO : Ignition 2.19.0 Sep 8 00:39:50.469622 ignition[925]: INFO : Stage: mount Sep 8 00:39:50.475164 ignition[925]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 8 00:39:50.475164 ignition[925]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 00:39:50.475164 ignition[925]: INFO : mount: mount passed Sep 8 00:39:50.475164 ignition[925]: INFO : Ignition finished successfully Sep 8 00:39:50.475985 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 8 00:39:50.491502 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 8 00:39:50.567745 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 8 00:39:50.579663 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 8 00:39:50.594054 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (937) Sep 8 00:39:50.594294 kernel: BTRFS info (device vda6): first mount of filesystem 12df91fc-3dfb-4662-9114-c5aeff8c3bed Sep 8 00:39:50.598719 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 8 00:39:50.599183 kernel: BTRFS info (device vda6): using free space tree Sep 8 00:39:50.606977 kernel: BTRFS info (device vda6): auto enabling async discard Sep 8 00:39:50.608416 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 8 00:39:50.642954 ignition[955]: INFO : Ignition 2.19.0 Sep 8 00:39:50.642954 ignition[955]: INFO : Stage: files Sep 8 00:39:50.647307 ignition[955]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 8 00:39:50.647307 ignition[955]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 00:39:50.647307 ignition[955]: DEBUG : files: compiled without relabeling support, skipping Sep 8 00:39:50.647307 ignition[955]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 8 00:39:50.647307 ignition[955]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 8 00:39:50.660790 ignition[955]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 8 00:39:50.660790 ignition[955]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 8 00:39:50.660790 ignition[955]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 8 00:39:50.655203 unknown[955]: wrote ssh authorized keys file for user: core Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.37.0-x86-64.raw" Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.37.0-x86-64.raw" Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.37.0-x86-64.raw" Sep 8 00:39:50.671177 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.37.0-x86-64.raw: attempt #1 Sep 8 00:39:50.785088 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 8 00:39:51.091795 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.37.0-x86-64.raw" Sep 8 00:39:51.091795 ignition[955]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 8 00:39:51.100627 ignition[955]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 8 00:39:51.100627 ignition[955]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 8 00:39:51.100627 ignition[955]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 8 00:39:51.100627 ignition[955]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 8 00:39:51.121016 ignition[955]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 8 00:39:51.128646 ignition[955]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 8 00:39:51.133006 ignition[955]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 8 00:39:51.133006 ignition[955]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 8 00:39:51.133006 ignition[955]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 8 00:39:51.133006 ignition[955]: INFO : files: files passed Sep 8 00:39:51.133006 ignition[955]: INFO : Ignition finished successfully Sep 8 00:39:51.131028 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 8 00:39:51.147399 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 8 00:39:51.154630 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 8 00:39:51.157403 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 8 00:39:51.157528 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 8 00:39:51.175311 initrd-setup-root-after-ignition[983]: grep: /sysroot/oem/oem-release: No such file or directory Sep 8 00:39:51.181596 initrd-setup-root-after-ignition[985]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 8 00:39:51.181596 initrd-setup-root-after-ignition[985]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 8 00:39:51.191555 initrd-setup-root-after-ignition[989]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 8 00:39:51.184641 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 8 00:39:51.186712 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 8 00:39:51.209497 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 8 00:39:51.239421 systemd-networkd[782]: eth0: Gained IPv6LL Sep 8 00:39:51.242607 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 8 00:39:51.242756 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 8 00:39:51.248305 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 8 00:39:51.249368 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 8 00:39:51.252864 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 8 00:39:51.264466 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 8 00:39:51.279496 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 8 00:39:51.300557 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 8 00:39:51.313868 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 8 00:39:51.315018 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 8 00:39:51.320100 systemd[1]: Stopped target timers.target - Timer Units. Sep 8 00:39:51.321009 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 8 00:39:51.321132 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 8 00:39:51.332210 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 8 00:39:51.333539 systemd[1]: Stopped target basic.target - Basic System. Sep 8 00:39:51.338647 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 8 00:39:51.344709 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 8 00:39:51.345525 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 8 00:39:51.349765 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 8 00:39:51.354124 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 8 00:39:51.354858 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 8 00:39:51.360191 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 8 00:39:51.360377 systemd[1]: Stopped target swap.target - Swaps. Sep 8 00:39:51.366233 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 8 00:39:51.366400 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 8 00:39:51.371341 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 8 00:39:51.373305 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 8 00:39:51.375871 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 8 00:39:51.378259 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 8 00:39:51.379171 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 8 00:39:51.379298 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 8 00:39:51.385007 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 8 00:39:51.385143 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 8 00:39:51.386686 systemd[1]: Stopped target paths.target - Path Units. Sep 8 00:39:51.391306 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 8 00:39:51.397344 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 8 00:39:51.403176 systemd[1]: Stopped target slices.target - Slice Units. Sep 8 00:39:51.403615 systemd[1]: Stopped target sockets.target - Socket Units. Sep 8 00:39:51.408455 systemd[1]: iscsid.socket: Deactivated successfully. Sep 8 00:39:51.408573 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 8 00:39:51.410642 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 8 00:39:51.410741 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 8 00:39:51.413385 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 8 00:39:51.413522 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 8 00:39:51.415818 systemd[1]: ignition-files.service: Deactivated successfully. Sep 8 00:39:51.416119 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 8 00:39:51.436617 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 8 00:39:51.439430 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 8 00:39:51.441647 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 8 00:39:51.441794 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 8 00:39:51.446359 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 8 00:39:51.446467 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 8 00:39:51.456248 ignition[1009]: INFO : Ignition 2.19.0 Sep 8 00:39:51.456248 ignition[1009]: INFO : Stage: umount Sep 8 00:39:51.456248 ignition[1009]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 8 00:39:51.456248 ignition[1009]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 8 00:39:51.465823 ignition[1009]: INFO : umount: umount passed Sep 8 00:39:51.465823 ignition[1009]: INFO : Ignition finished successfully Sep 8 00:39:51.457113 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 8 00:39:51.457242 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 8 00:39:51.459046 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 8 00:39:51.459363 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 8 00:39:51.466007 systemd[1]: Stopped target network.target - Network. Sep 8 00:39:51.466703 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 8 00:39:51.466775 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 8 00:39:51.470702 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 8 00:39:51.470769 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 8 00:39:51.473816 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 8 00:39:51.474173 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 8 00:39:51.477197 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 8 00:39:51.477255 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 8 00:39:51.477869 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 8 00:39:51.482572 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 8 00:39:51.487125 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 8 00:39:51.491605 systemd-networkd[782]: eth0: DHCPv6 lease lost Sep 8 00:39:51.497961 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 8 00:39:51.498231 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 8 00:39:51.500271 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 8 00:39:51.500313 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 8 00:39:51.511060 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 8 00:39:51.513748 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 8 00:39:51.514122 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 8 00:39:51.522945 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 8 00:39:51.537648 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 8 00:39:51.537750 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 8 00:39:51.541651 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 8 00:39:51.541793 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 8 00:39:51.553591 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 8 00:39:51.553811 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 8 00:39:51.556562 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 8 00:39:51.556605 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 8 00:39:51.557506 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 8 00:39:51.557537 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 8 00:39:51.561780 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 8 00:39:51.561860 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 8 00:39:51.566115 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 8 00:39:51.566188 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 8 00:39:51.570718 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 8 00:39:51.571154 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 8 00:39:51.575207 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 8 00:39:51.575295 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 8 00:39:51.580483 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 8 00:39:51.581366 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 8 00:39:51.581464 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 8 00:39:51.586183 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 8 00:39:51.586357 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 8 00:39:51.590594 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 8 00:39:51.590670 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 8 00:39:51.596428 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 8 00:39:51.596516 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 8 00:39:51.597840 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 8 00:39:51.598165 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 8 00:39:51.604815 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 8 00:39:51.604941 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 8 00:39:51.609997 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 8 00:39:51.610148 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 00:39:51.615143 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 8 00:39:51.615266 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 8 00:39:51.620459 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 8 00:39:51.620559 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 8 00:39:51.629545 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 8 00:39:51.654518 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 8 00:39:51.668305 systemd[1]: Switching root. Sep 8 00:39:51.695404 systemd-journald[193]: Journal stopped Sep 8 00:39:52.617813 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). Sep 8 00:39:52.617964 kernel: SELinux: policy capability network_peer_controls=1 Sep 8 00:39:52.617986 kernel: SELinux: policy capability open_perms=1 Sep 8 00:39:52.617998 kernel: SELinux: policy capability extended_socket_class=1 Sep 8 00:39:52.618011 kernel: SELinux: policy capability always_check_network=0 Sep 8 00:39:52.618024 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 8 00:39:52.618036 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 8 00:39:52.618047 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 8 00:39:52.618060 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 8 00:39:52.618077 kernel: audit: type=1403 audit(1788827991.839:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 8 00:39:52.618099 systemd[1]: Successfully loaded SELinux policy in 55.281ms. Sep 8 00:39:52.618129 kernel: hrtimer: interrupt took 2705874 ns Sep 8 00:39:52.618149 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 13.302ms. Sep 8 00:39:52.618163 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 8 00:39:52.618178 systemd[1]: Detected virtualization kvm. Sep 8 00:39:52.618192 systemd[1]: Detected architecture x86-64. Sep 8 00:39:52.618206 systemd[1]: Detected first boot. Sep 8 00:39:52.618220 systemd[1]: Initializing machine ID from VM UUID. Sep 8 00:39:52.618239 zram_generator::config[1054]: No configuration found. Sep 8 00:39:52.618255 systemd[1]: Populated /etc with preset unit settings. Sep 8 00:39:52.618268 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 8 00:39:52.618283 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 8 00:39:52.618296 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 8 00:39:52.618314 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 8 00:39:52.618329 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 8 00:39:52.618343 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 8 00:39:52.618355 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 8 00:39:52.618368 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 8 00:39:52.618383 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 8 00:39:52.618402 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 8 00:39:52.618417 systemd[1]: Created slice user.slice - User and Session Slice. Sep 8 00:39:52.618433 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 8 00:39:52.618449 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 8 00:39:52.618463 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 8 00:39:52.618477 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 8 00:39:52.618492 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 8 00:39:52.618507 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 8 00:39:52.618520 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 8 00:39:52.618535 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 8 00:39:52.618549 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 8 00:39:52.618566 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 8 00:39:52.618580 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 8 00:39:52.618594 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 8 00:39:52.618608 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 8 00:39:52.618622 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 8 00:39:52.618636 systemd[1]: Reached target slices.target - Slice Units. Sep 8 00:39:52.618650 systemd[1]: Reached target swap.target - Swaps. Sep 8 00:39:52.618664 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 8 00:39:52.618681 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 8 00:39:52.618694 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 8 00:39:52.618708 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 8 00:39:52.618722 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 8 00:39:52.618737 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 8 00:39:52.618759 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 8 00:39:52.618773 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 8 00:39:52.618787 systemd[1]: Mounting media.mount - External Media Directory... Sep 8 00:39:52.618801 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 8 00:39:52.618817 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 8 00:39:52.618832 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 8 00:39:52.618846 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 8 00:39:52.618861 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 8 00:39:52.618875 systemd[1]: Reached target machines.target - Containers. Sep 8 00:39:52.619455 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 8 00:39:52.619476 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 8 00:39:52.619492 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 8 00:39:52.619515 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 8 00:39:52.619530 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 8 00:39:52.619546 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 8 00:39:52.619560 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 8 00:39:52.619576 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 8 00:39:52.619590 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 8 00:39:52.619607 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 8 00:39:52.619621 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 8 00:39:52.619636 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 8 00:39:52.619656 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 8 00:39:52.619670 kernel: fuse: init (API version 7.39) Sep 8 00:39:52.619685 kernel: loop: module loaded Sep 8 00:39:52.619699 systemd[1]: Stopped systemd-fsck-usr.service. Sep 8 00:39:52.619714 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 8 00:39:52.619730 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 8 00:39:52.619745 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 8 00:39:52.619759 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 8 00:39:52.619803 systemd-journald[1128]: Collecting audit messages is disabled. Sep 8 00:39:52.619837 systemd-journald[1128]: Journal started Sep 8 00:39:52.619867 systemd-journald[1128]: Runtime Journal (/run/log/journal/bcb1d9aada7244149f520e1d0f6d1158) is 6.0M, max 48.3M, 42.3M free. Sep 8 00:39:52.312455 systemd[1]: Queued start job for default target multi-user.target. Sep 8 00:39:52.338221 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 8 00:39:52.339311 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 8 00:39:52.624624 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 8 00:39:52.624671 systemd[1]: verity-setup.service: Deactivated successfully. Sep 8 00:39:52.629152 kernel: ACPI: bus type drm_connector registered Sep 8 00:39:52.629207 systemd[1]: Stopped verity-setup.service. Sep 8 00:39:52.634959 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 8 00:39:52.655105 systemd[1]: Started systemd-journald.service - Journal Service. Sep 8 00:39:52.657648 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 8 00:39:52.660322 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 8 00:39:52.663271 systemd[1]: Mounted media.mount - External Media Directory. Sep 8 00:39:52.665536 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 8 00:39:52.668256 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 8 00:39:52.671223 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 8 00:39:52.673751 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 8 00:39:52.677274 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 8 00:39:52.680043 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 8 00:39:52.680279 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 8 00:39:52.683241 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 8 00:39:52.683613 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 8 00:39:52.686328 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 8 00:39:52.686677 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 8 00:39:52.690310 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 8 00:39:52.690653 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 8 00:39:52.694641 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 8 00:39:52.694970 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 8 00:39:52.698435 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 8 00:39:52.699114 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 8 00:39:52.705034 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 8 00:39:52.708365 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 8 00:39:52.711709 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 8 00:39:52.727270 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 8 00:39:52.738255 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 8 00:39:52.742413 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 8 00:39:52.745184 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 8 00:39:52.745266 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 8 00:39:52.747776 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 8 00:39:52.752769 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 8 00:39:52.757106 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 8 00:39:52.759153 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 8 00:39:52.763092 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 8 00:39:52.767250 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 8 00:39:52.769608 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 8 00:39:52.773174 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 8 00:39:52.775728 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 8 00:39:52.779753 systemd-journald[1128]: Time spent on flushing to /var/log/journal/bcb1d9aada7244149f520e1d0f6d1158 is 25.113ms for 934 entries. Sep 8 00:39:52.779753 systemd-journald[1128]: System Journal (/var/log/journal/bcb1d9aada7244149f520e1d0f6d1158) is 8.0M, max 195.6M, 187.6M free. Sep 8 00:39:52.822411 systemd-journald[1128]: Received client request to flush runtime journal. Sep 8 00:39:52.822458 kernel: loop0: detected capacity change from 0 to 142488 Sep 8 00:39:52.781152 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 8 00:39:52.787048 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 8 00:39:52.793992 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 8 00:39:52.798856 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 8 00:39:52.801367 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 8 00:39:52.804224 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 8 00:39:52.807108 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 8 00:39:52.810276 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 8 00:39:52.816354 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 8 00:39:52.831510 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 8 00:39:52.837004 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 8 00:39:52.840418 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 8 00:39:52.843535 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 8 00:39:52.850326 systemd-tmpfiles[1170]: ACLs are not supported, ignoring. Sep 8 00:39:52.850347 systemd-tmpfiles[1170]: ACLs are not supported, ignoring. Sep 8 00:39:52.859955 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 8 00:39:52.860558 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 8 00:39:52.870288 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 8 00:39:52.878269 udevadm[1182]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 8 00:39:52.879851 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 8 00:39:52.880783 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 8 00:39:52.900935 kernel: loop1: detected capacity change from 0 to 140768 Sep 8 00:39:52.909800 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 8 00:39:52.920467 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 8 00:39:52.938222 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Sep 8 00:39:52.938577 systemd-tmpfiles[1192]: ACLs are not supported, ignoring. Sep 8 00:39:52.941380 kernel: loop2: detected capacity change from 0 to 221624 Sep 8 00:39:52.945197 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 8 00:39:52.985317 kernel: loop3: detected capacity change from 0 to 142488 Sep 8 00:39:53.006025 kernel: loop4: detected capacity change from 0 to 140768 Sep 8 00:39:53.027152 kernel: loop5: detected capacity change from 0 to 221624 Sep 8 00:39:53.039481 (sd-merge)[1198]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 8 00:39:53.040435 (sd-merge)[1198]: Merged extensions into '/usr'. Sep 8 00:39:53.047182 systemd[1]: Reloading requested from client PID 1168 ('systemd-sysext') (unit systemd-sysext.service)... Sep 8 00:39:53.047209 systemd[1]: Reloading... Sep 8 00:39:53.109369 zram_generator::config[1223]: No configuration found. Sep 8 00:39:53.221821 ldconfig[1163]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 8 00:39:53.255166 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 8 00:39:53.309842 systemd[1]: Reloading finished in 262 ms. Sep 8 00:39:53.340731 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 8 00:39:53.343255 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 8 00:39:53.355302 systemd[1]: Starting ensure-sysext.service... Sep 8 00:39:53.358130 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 8 00:39:53.366530 systemd[1]: Reloading requested from client PID 1261 ('systemctl') (unit ensure-sysext.service)... Sep 8 00:39:53.366715 systemd[1]: Reloading... Sep 8 00:39:53.383588 systemd-tmpfiles[1262]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 8 00:39:53.384056 systemd-tmpfiles[1262]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 8 00:39:53.385078 systemd-tmpfiles[1262]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 8 00:39:53.385262 systemd-tmpfiles[1262]: ACLs are not supported, ignoring. Sep 8 00:39:53.385310 systemd-tmpfiles[1262]: ACLs are not supported, ignoring. Sep 8 00:39:53.388610 systemd-tmpfiles[1262]: Detected autofs mount point /boot during canonicalization of boot. Sep 8 00:39:53.388638 systemd-tmpfiles[1262]: Skipping /boot Sep 8 00:39:53.399379 systemd-tmpfiles[1262]: Detected autofs mount point /boot during canonicalization of boot. Sep 8 00:39:53.399393 systemd-tmpfiles[1262]: Skipping /boot Sep 8 00:39:53.419946 zram_generator::config[1290]: No configuration found. Sep 8 00:39:53.539221 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 8 00:39:53.585853 systemd[1]: Reloading finished in 218 ms. Sep 8 00:39:53.609353 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 8 00:39:53.636114 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 8 00:39:53.658604 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 8 00:39:53.665320 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 8 00:39:53.671735 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 8 00:39:53.678560 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 8 00:39:53.687191 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 8 00:39:53.701414 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 8 00:39:53.715129 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 8 00:39:53.718351 augenrules[1350]: No rules Sep 8 00:39:53.721548 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 8 00:39:53.726800 systemd[1]: Finished ensure-sysext.service. Sep 8 00:39:53.733465 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 8 00:39:53.739634 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 8 00:39:53.739850 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 8 00:39:53.742153 systemd-udevd[1341]: Using default interface naming scheme 'v255'. Sep 8 00:39:53.747290 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 8 00:39:53.751204 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 8 00:39:53.754504 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 8 00:39:53.760552 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 8 00:39:53.762283 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 8 00:39:53.764997 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 8 00:39:53.770634 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 8 00:39:53.777082 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 8 00:39:53.779201 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 8 00:39:53.779634 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 8 00:39:53.782302 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 8 00:39:53.782601 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 8 00:39:53.785290 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 8 00:39:53.785602 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 8 00:39:53.788340 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 8 00:39:53.789504 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 8 00:39:53.792484 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 8 00:39:53.792815 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 8 00:39:53.797110 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 8 00:39:53.800443 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 8 00:39:53.827392 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 8 00:39:53.831803 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 8 00:39:53.832072 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 8 00:39:53.832115 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 8 00:39:53.841016 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1389) Sep 8 00:39:53.853244 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 8 00:39:53.871378 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 8 00:39:53.926158 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Sep 8 00:39:53.931493 kernel: ACPI: button: Power Button [PWRF] Sep 8 00:39:53.949564 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 8 00:39:53.962250 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 8 00:39:53.972654 systemd-networkd[1385]: lo: Link UP Sep 8 00:39:53.973225 systemd-networkd[1385]: lo: Gained carrier Sep 8 00:39:53.975329 systemd-networkd[1385]: Enumeration completed Sep 8 00:39:53.975447 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 8 00:39:53.978161 systemd-networkd[1385]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 00:39:53.978169 systemd-networkd[1385]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 8 00:39:53.982118 systemd-networkd[1385]: eth0: Link UP Sep 8 00:39:53.989455 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 8 00:39:53.989792 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Sep 8 00:39:53.990227 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 8 00:39:53.985226 systemd-networkd[1385]: eth0: Gained carrier Sep 8 00:39:53.985258 systemd-networkd[1385]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 8 00:39:53.996361 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 8 00:39:54.012125 systemd-networkd[1385]: eth0: DHCPv4 address 10.0.0.145/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 8 00:39:54.014983 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 8 00:39:54.025608 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 8 00:39:54.037623 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 8 00:39:54.041768 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 8 00:39:54.047564 systemd[1]: Reached target time-set.target - System Time Set. Sep 8 00:39:54.780957 systemd-timesyncd[1363]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 8 00:39:54.781034 systemd-timesyncd[1363]: Initial clock synchronization to Tue 2026-09-08 00:39:54.780536 UTC. Sep 8 00:39:54.781888 systemd-resolved[1339]: Positive Trust Anchors: Sep 8 00:39:54.782272 systemd-resolved[1339]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 8 00:39:54.782360 systemd-resolved[1339]: 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 8 00:39:54.791115 systemd-resolved[1339]: Defaulting to hostname 'linux'. Sep 8 00:39:54.794819 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 8 00:39:54.797996 systemd[1]: Reached target network.target - Network. Sep 8 00:39:54.801006 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 8 00:39:54.931690 kernel: mousedev: PS/2 mouse device common for all mice Sep 8 00:39:55.022533 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 8 00:39:55.047250 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 8 00:39:55.066142 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 8 00:39:55.082021 lvm[1423]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 8 00:39:55.113404 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 8 00:39:55.118202 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 8 00:39:55.122427 systemd[1]: Reached target sysinit.target - System Initialization. Sep 8 00:39:55.127127 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 8 00:39:55.129778 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 8 00:39:55.133271 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 8 00:39:55.136243 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 8 00:39:55.139974 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 8 00:39:55.143704 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 8 00:39:55.143780 systemd[1]: Reached target paths.target - Path Units. Sep 8 00:39:55.145887 systemd[1]: Reached target timers.target - Timer Units. Sep 8 00:39:55.155559 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 8 00:39:55.162435 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 8 00:39:55.178415 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 8 00:39:55.184222 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 8 00:39:55.189081 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 8 00:39:55.192563 systemd[1]: Reached target sockets.target - Socket Units. Sep 8 00:39:55.195398 systemd[1]: Reached target basic.target - Basic System. Sep 8 00:39:55.197459 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 8 00:39:55.197509 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 8 00:39:55.201717 lvm[1427]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 8 00:39:55.206046 systemd[1]: Starting containerd.service - containerd container runtime... Sep 8 00:39:55.210104 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 8 00:39:55.215056 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 8 00:39:55.222049 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 8 00:39:55.225198 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 8 00:39:55.231866 jq[1430]: false Sep 8 00:39:55.232162 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 8 00:39:55.247289 extend-filesystems[1431]: Found loop3 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found loop4 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found loop5 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found sr0 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda1 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda2 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda3 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found usr Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda4 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda6 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda7 Sep 8 00:39:55.247289 extend-filesystems[1431]: Found vda9 Sep 8 00:39:55.247289 extend-filesystems[1431]: Checking size of /dev/vda9 Sep 8 00:39:55.332034 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 31 scanned by (udev-worker) (1373) Sep 8 00:39:55.247156 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 8 00:39:55.259417 dbus-daemon[1429]: [system] SELinux support is enabled Sep 8 00:39:55.332415 extend-filesystems[1431]: Resized partition /dev/vda9 Sep 8 00:39:55.337071 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 8 00:39:55.253012 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 8 00:39:55.337190 extend-filesystems[1447]: resize2fs 1.47.1 (20-May-2024) Sep 8 00:39:55.270704 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 8 00:39:55.272698 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 8 00:39:55.273471 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 8 00:39:55.342332 jq[1449]: true Sep 8 00:39:55.277253 systemd[1]: Starting update-engine.service - Update Engine... Sep 8 00:39:55.283227 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 8 00:39:55.342753 update_engine[1446]: I20260908 00:39:55.324438 1446 main.cc:92] Flatcar Update Engine starting Sep 8 00:39:55.342753 update_engine[1446]: I20260908 00:39:55.340914 1446 update_check_scheduler.cc:74] Next update check in 10m7s Sep 8 00:39:55.286080 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 8 00:39:55.291313 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 8 00:39:55.295897 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 8 00:39:55.296135 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 8 00:39:55.296627 systemd[1]: motdgen.service: Deactivated successfully. Sep 8 00:39:55.297114 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 8 00:39:55.304773 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 8 00:39:55.304981 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 8 00:39:55.340027 (ntainerd)[1453]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 8 00:39:55.348092 jq[1452]: true Sep 8 00:39:55.368829 systemd[1]: Started update-engine.service - Update Engine. Sep 8 00:39:55.370048 systemd-logind[1440]: Watching system buttons on /dev/input/event1 (Power Button) Sep 8 00:39:55.370100 systemd-logind[1440]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 8 00:39:55.372920 systemd-logind[1440]: New seat seat0. Sep 8 00:39:55.374924 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 8 00:39:55.374964 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 8 00:39:55.379086 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 8 00:39:55.379139 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 8 00:39:55.399379 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 8 00:39:55.407748 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 8 00:39:55.413997 systemd[1]: Started systemd-logind.service - User Login Management. Sep 8 00:39:55.455926 locksmithd[1478]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 8 00:39:55.463126 extend-filesystems[1447]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 8 00:39:55.463126 extend-filesystems[1447]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 8 00:39:55.463126 extend-filesystems[1447]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 8 00:39:55.474825 extend-filesystems[1431]: Resized filesystem in /dev/vda9 Sep 8 00:39:55.474230 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 8 00:39:55.476560 bash[1479]: Updated "/home/core/.ssh/authorized_keys" Sep 8 00:39:55.474720 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 8 00:39:55.481136 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 8 00:39:55.487114 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 8 00:39:55.681812 containerd[1453]: time="2026-09-08T00:39:55.679992209Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Sep 8 00:39:55.724090 containerd[1453]: time="2026-09-08T00:39:55.723371528Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.728773064Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.156-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.728843224Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.728872895Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729073873Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729103935Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729168162Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729182000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729369813Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729385618Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729401480Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730358 containerd[1453]: time="2026-09-08T00:39:55.729412478Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730810 containerd[1453]: time="2026-09-08T00:39:55.729487922Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730810 containerd[1453]: time="2026-09-08T00:39:55.729773646Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730810 containerd[1453]: time="2026-09-08T00:39:55.729904176Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 8 00:39:55.730810 containerd[1453]: time="2026-09-08T00:39:55.729921493Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 8 00:39:55.730810 containerd[1453]: time="2026-09-08T00:39:55.730007412Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 8 00:39:55.730810 containerd[1453]: time="2026-09-08T00:39:55.730056287Z" level=info msg="metadata content store policy set" policy=shared Sep 8 00:39:55.740165 containerd[1453]: time="2026-09-08T00:39:55.739546023Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 8 00:39:55.740820 containerd[1453]: time="2026-09-08T00:39:55.740380425Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 8 00:39:55.741063 containerd[1453]: time="2026-09-08T00:39:55.740998794Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 8 00:39:55.741063 containerd[1453]: time="2026-09-08T00:39:55.741055979Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 8 00:39:55.741198 containerd[1453]: time="2026-09-08T00:39:55.741083354Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 8 00:39:55.741339 containerd[1453]: time="2026-09-08T00:39:55.741300562Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 8 00:39:55.741818 containerd[1453]: time="2026-09-08T00:39:55.741754206Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 8 00:39:55.742060 containerd[1453]: time="2026-09-08T00:39:55.742010017Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 8 00:39:55.742101 containerd[1453]: time="2026-09-08T00:39:55.742057026Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 8 00:39:55.742101 containerd[1453]: time="2026-09-08T00:39:55.742078615Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 8 00:39:55.742165 containerd[1453]: time="2026-09-08T00:39:55.742101408Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742165 containerd[1453]: time="2026-09-08T00:39:55.742120062Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742165 containerd[1453]: time="2026-09-08T00:39:55.742151263Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742228 containerd[1453]: time="2026-09-08T00:39:55.742173152Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742228 containerd[1453]: time="2026-09-08T00:39:55.742193118Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742228 containerd[1453]: time="2026-09-08T00:39:55.742211644Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742293 containerd[1453]: time="2026-09-08T00:39:55.742228804Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742293 containerd[1453]: time="2026-09-08T00:39:55.742244437Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 8 00:39:55.742293 containerd[1453]: time="2026-09-08T00:39:55.742272590Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742355 containerd[1453]: time="2026-09-08T00:39:55.742290979Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742355 containerd[1453]: time="2026-09-08T00:39:55.742307640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742355 containerd[1453]: time="2026-09-08T00:39:55.742324310Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742355 containerd[1453]: time="2026-09-08T00:39:55.742340747Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742450 containerd[1453]: time="2026-09-08T00:39:55.742362403Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742450 containerd[1453]: time="2026-09-08T00:39:55.742380248Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742450 containerd[1453]: time="2026-09-08T00:39:55.742400089Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742450 containerd[1453]: time="2026-09-08T00:39:55.742417505Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742450 containerd[1453]: time="2026-09-08T00:39:55.742437083Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742566 containerd[1453]: time="2026-09-08T00:39:55.742452831Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742566 containerd[1453]: time="2026-09-08T00:39:55.742469040Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742566 containerd[1453]: time="2026-09-08T00:39:55.742485691Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742566 containerd[1453]: time="2026-09-08T00:39:55.742507032Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 8 00:39:55.742566 containerd[1453]: time="2026-09-08T00:39:55.742540888Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742566 containerd[1453]: time="2026-09-08T00:39:55.742557263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742752 containerd[1453]: time="2026-09-08T00:39:55.742576990Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 8 00:39:55.742752 containerd[1453]: time="2026-09-08T00:39:55.742705310Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 8 00:39:55.742752 containerd[1453]: time="2026-09-08T00:39:55.742736990Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Sep 8 00:39:55.742829 containerd[1453]: time="2026-09-08T00:39:55.742755429Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 8 00:39:55.742829 containerd[1453]: time="2026-09-08T00:39:55.742774047Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 8 00:39:55.742829 containerd[1453]: time="2026-09-08T00:39:55.742791088Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.742829 containerd[1453]: time="2026-09-08T00:39:55.742807773Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 8 00:39:55.742829 containerd[1453]: time="2026-09-08T00:39:55.742820798Z" level=info msg="NRI interface is disabled by configuration." Sep 8 00:39:55.742933 containerd[1453]: time="2026-09-08T00:39:55.742835028Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 8 00:39:55.743233 containerd[1453]: time="2026-09-08T00:39:55.743161327Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Sep 8 00:39:55.743233 containerd[1453]: time="2026-09-08T00:39:55.743231411Z" level=info msg="Connect containerd service" Sep 8 00:39:55.743442 containerd[1453]: time="2026-09-08T00:39:55.743285732Z" level=info msg="using legacy CRI server" Sep 8 00:39:55.743442 containerd[1453]: time="2026-09-08T00:39:55.743294931Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 8 00:39:55.743442 containerd[1453]: time="2026-09-08T00:39:55.743441516Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 8 00:39:55.744363 containerd[1453]: time="2026-09-08T00:39:55.744306090Z" 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 8 00:39:55.744707 containerd[1453]: time="2026-09-08T00:39:55.744550340Z" level=info msg="Start subscribing containerd event" Sep 8 00:39:55.744755 containerd[1453]: time="2026-09-08T00:39:55.744731475Z" level=info msg="Start recovering state" Sep 8 00:39:55.745382 containerd[1453]: time="2026-09-08T00:39:55.745304530Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 8 00:39:55.747372 containerd[1453]: time="2026-09-08T00:39:55.746013497Z" level=info msg="Start event monitor" Sep 8 00:39:55.747372 containerd[1453]: time="2026-09-08T00:39:55.746057437Z" level=info msg="Start snapshots syncer" Sep 8 00:39:55.747372 containerd[1453]: time="2026-09-08T00:39:55.746073032Z" level=info msg="Start cni network conf syncer for default" Sep 8 00:39:55.747372 containerd[1453]: time="2026-09-08T00:39:55.746083008Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 8 00:39:55.747372 containerd[1453]: time="2026-09-08T00:39:55.746086860Z" level=info msg="Start streaming server" Sep 8 00:39:55.746674 systemd[1]: Started containerd.service - containerd container runtime. Sep 8 00:39:55.749182 containerd[1453]: time="2026-09-08T00:39:55.749133271Z" level=info msg="containerd successfully booted in 0.070597s" Sep 8 00:39:56.051132 sshd_keygen[1458]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 8 00:39:56.089358 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 8 00:39:56.104248 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 8 00:39:56.117009 systemd[1]: issuegen.service: Deactivated successfully. Sep 8 00:39:56.117359 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 8 00:39:56.141867 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 8 00:39:56.164070 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 8 00:39:56.181365 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 8 00:39:56.195469 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 8 00:39:56.202064 systemd[1]: Reached target getty.target - Login Prompts. Sep 8 00:39:56.640709 systemd-networkd[1385]: eth0: Gained IPv6LL Sep 8 00:39:56.659182 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 8 00:39:56.665924 systemd[1]: Reached target network-online.target - Network is Online. Sep 8 00:39:56.685443 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 8 00:39:56.699083 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 8 00:39:56.706051 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 8 00:39:56.759417 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 8 00:39:56.760517 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 8 00:39:56.769842 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 8 00:39:56.784231 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 8 00:39:57.744770 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 00:39:57.749904 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 8 00:39:57.751935 (kubelet)[1535]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 8 00:39:57.762059 systemd[1]: Startup finished in 1.019s (kernel) + 5.097s (initrd) + 5.242s (userspace) = 11.360s. Sep 8 00:39:58.355661 kubelet[1535]: E0908 00:39:58.355097 1535 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 8 00:39:58.359523 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 8 00:39:58.360594 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 8 00:39:58.362908 systemd[1]: kubelet.service: Consumed 1.177s CPU time. Sep 8 00:40:01.597079 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 8 00:40:01.599161 systemd[1]: Started sshd@0-10.0.0.145:22-10.0.0.1:44458.service - OpenSSH per-connection server daemon (10.0.0.1:44458). Sep 8 00:40:01.677270 sshd[1549]: Accepted publickey for core from 10.0.0.1 port 44458 ssh2: RSA SHA256:HpQTr0fMKKrKS5UjXgF198wvBvD4I6ItrJqGdkuAzc4 Sep 8 00:40:01.681339 sshd[1549]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 00:40:01.696854 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 8 00:40:01.708477 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 8 00:40:01.712486 systemd-logind[1440]: New session 1 of user core. Sep 8 00:40:01.730053 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 8 00:40:01.749803 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 8 00:40:01.754193 (systemd)[1553]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 8 00:40:01.911932 systemd[1553]: Queued start job for default target default.target. Sep 8 00:40:01.928003 systemd[1553]: Created slice app.slice - User Application Slice. Sep 8 00:40:01.928184 systemd[1553]: Reached target paths.target - Paths. Sep 8 00:40:01.928204 systemd[1553]: Reached target timers.target - Timers. Sep 8 00:40:01.931029 systemd[1553]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 8 00:40:01.951958 systemd[1553]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 8 00:40:01.952157 systemd[1553]: Reached target sockets.target - Sockets. Sep 8 00:40:01.952175 systemd[1553]: Reached target basic.target - Basic System. Sep 8 00:40:01.952243 systemd[1553]: Reached target default.target - Main User Target. Sep 8 00:40:01.952277 systemd[1553]: Startup finished in 188ms. Sep 8 00:40:01.952416 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 8 00:40:01.962275 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 8 00:40:02.034390 systemd[1]: Started sshd@1-10.0.0.145:22-10.0.0.1:44474.service - OpenSSH per-connection server daemon (10.0.0.1:44474). Sep 8 00:40:02.085128 sshd[1564]: Accepted publickey for core from 10.0.0.1 port 44474 ssh2: RSA SHA256:HpQTr0fMKKrKS5UjXgF198wvBvD4I6ItrJqGdkuAzc4 Sep 8 00:40:02.087782 sshd[1564]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 00:40:02.096011 systemd-logind[1440]: New session 2 of user core. Sep 8 00:40:02.106293 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 8 00:40:02.169608 sshd[1564]: pam_unix(sshd:session): session closed for user core Sep 8 00:40:02.185457 systemd[1]: sshd@1-10.0.0.145:22-10.0.0.1:44474.service: Deactivated successfully. Sep 8 00:40:02.188454 systemd[1]: session-2.scope: Deactivated successfully. Sep 8 00:40:02.191149 systemd-logind[1440]: Session 2 logged out. Waiting for processes to exit. Sep 8 00:40:02.199268 systemd[1]: Started sshd@2-10.0.0.145:22-10.0.0.1:44484.service - OpenSSH per-connection server daemon (10.0.0.1:44484). Sep 8 00:40:02.200738 systemd-logind[1440]: Removed session 2. Sep 8 00:40:02.234839 sshd[1571]: Accepted publickey for core from 10.0.0.1 port 44484 ssh2: RSA SHA256:HpQTr0fMKKrKS5UjXgF198wvBvD4I6ItrJqGdkuAzc4 Sep 8 00:40:02.237475 sshd[1571]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 00:40:02.245006 systemd-logind[1440]: New session 3 of user core. Sep 8 00:40:02.255421 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 8 00:40:02.313178 sshd[1571]: pam_unix(sshd:session): session closed for user core Sep 8 00:40:02.328611 systemd[1]: sshd@2-10.0.0.145:22-10.0.0.1:44484.service: Deactivated successfully. Sep 8 00:40:02.331525 systemd[1]: session-3.scope: Deactivated successfully. Sep 8 00:40:02.333558 systemd-logind[1440]: Session 3 logged out. Waiting for processes to exit. Sep 8 00:40:02.344834 systemd[1]: Started sshd@3-10.0.0.145:22-10.0.0.1:44490.service - OpenSSH per-connection server daemon (10.0.0.1:44490). Sep 8 00:40:02.346611 systemd-logind[1440]: Removed session 3. Sep 8 00:40:02.389781 sshd[1578]: Accepted publickey for core from 10.0.0.1 port 44490 ssh2: RSA SHA256:HpQTr0fMKKrKS5UjXgF198wvBvD4I6ItrJqGdkuAzc4 Sep 8 00:40:02.392857 sshd[1578]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 00:40:02.403822 systemd-logind[1440]: New session 4 of user core. Sep 8 00:40:02.418364 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 8 00:40:02.481602 sshd[1578]: pam_unix(sshd:session): session closed for user core Sep 8 00:40:02.491337 systemd[1]: sshd@3-10.0.0.145:22-10.0.0.1:44490.service: Deactivated successfully. Sep 8 00:40:02.494057 systemd[1]: session-4.scope: Deactivated successfully. Sep 8 00:40:02.496381 systemd-logind[1440]: Session 4 logged out. Waiting for processes to exit. Sep 8 00:40:02.509838 systemd[1]: Started sshd@4-10.0.0.145:22-10.0.0.1:44502.service - OpenSSH per-connection server daemon (10.0.0.1:44502). Sep 8 00:40:02.511279 systemd-logind[1440]: Removed session 4. Sep 8 00:40:02.548859 sshd[1585]: Accepted publickey for core from 10.0.0.1 port 44502 ssh2: RSA SHA256:HpQTr0fMKKrKS5UjXgF198wvBvD4I6ItrJqGdkuAzc4 Sep 8 00:40:02.551604 sshd[1585]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 8 00:40:02.562193 systemd-logind[1440]: New session 5 of user core. Sep 8 00:40:02.572215 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 8 00:40:02.642206 sudo[1588]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 8 00:40:02.642862 sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 8 00:40:02.680344 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 8 00:40:02.709811 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 8 00:40:02.710168 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 8 00:40:03.549419 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 00:40:03.549891 systemd[1]: kubelet.service: Consumed 1.177s CPU time. Sep 8 00:40:03.562428 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 8 00:40:03.602723 systemd[1]: Reloading requested from client PID 1638 ('systemctl') (unit session-5.scope)... Sep 8 00:40:03.602752 systemd[1]: Reloading... Sep 8 00:40:03.695879 zram_generator::config[1677]: No configuration found. Sep 8 00:40:03.830105 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 8 00:40:03.888917 systemd[1]: Reloading finished in 285 ms. Sep 8 00:40:03.937434 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 00:40:03.941381 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Sep 8 00:40:03.943249 systemd[1]: kubelet.service: Deactivated successfully. Sep 8 00:40:03.943884 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 00:40:03.954318 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 8 00:40:04.089096 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 8 00:40:04.096405 (kubelet)[1726]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 8 00:40:04.155376 kubelet[1726]: 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 8 00:40:04.160285 kubelet[1726]: I0908 00:40:04.160149 1726 server.go:257] "Effective KubeletConfiguration" config=< Sep 8 00:40:04.160285 kubelet[1726]: address: 0.0.0.0 Sep 8 00:40:04.160285 kubelet[1726]: apiVersion: kubelet.config.k8s.io/v1beta1 Sep 8 00:40:04.160285 kubelet[1726]: authentication: Sep 8 00:40:04.160285 kubelet[1726]: anonymous: Sep 8 00:40:04.160285 kubelet[1726]: enabled: false Sep 8 00:40:04.160285 kubelet[1726]: webhook: Sep 8 00:40:04.160285 kubelet[1726]: cacheTTL: 2m0s Sep 8 00:40:04.160285 kubelet[1726]: enabled: true Sep 8 00:40:04.160285 kubelet[1726]: x509: Sep 8 00:40:04.160285 kubelet[1726]: clientCAFile: /etc/kubernetes/pki/ca.crt Sep 8 00:40:04.160285 kubelet[1726]: authorization: Sep 8 00:40:04.160285 kubelet[1726]: mode: Webhook Sep 8 00:40:04.160285 kubelet[1726]: webhook: Sep 8 00:40:04.160285 kubelet[1726]: cacheAuthorizedTTL: 5m0s Sep 8 00:40:04.160285 kubelet[1726]: cacheUnauthorizedTTL: 30s Sep 8 00:40:04.160285 kubelet[1726]: cgroupDriver: systemd Sep 8 00:40:04.160285 kubelet[1726]: cgroupsPerQOS: true Sep 8 00:40:04.160285 kubelet[1726]: clusterDNS: Sep 8 00:40:04.160285 kubelet[1726]: - 10.96.0.10 Sep 8 00:40:04.160285 kubelet[1726]: clusterDomain: cluster.local Sep 8 00:40:04.160285 kubelet[1726]: configMapAndSecretChangeDetectionStrategy: Watch Sep 8 00:40:04.160285 kubelet[1726]: containerLogMaxFiles: 5 Sep 8 00:40:04.160285 kubelet[1726]: containerLogMaxSize: 10Mi Sep 8 00:40:04.160285 kubelet[1726]: containerLogMaxWorkers: 1 Sep 8 00:40:04.160285 kubelet[1726]: containerLogMonitorInterval: 10s Sep 8 00:40:04.160285 kubelet[1726]: containerRuntimeEndpoint: unix:///var/run/containerd/containerd.sock Sep 8 00:40:04.160285 kubelet[1726]: contentType: application/vnd.kubernetes.protobuf Sep 8 00:40:04.160285 kubelet[1726]: cpuCFSQuota: true Sep 8 00:40:04.160285 kubelet[1726]: cpuCFSQuotaPeriod: 100ms Sep 8 00:40:04.160285 kubelet[1726]: cpuManagerPolicy: none Sep 8 00:40:04.160285 kubelet[1726]: cpuManagerReconcilePeriod: 10s Sep 8 00:40:04.160285 kubelet[1726]: crashLoopBackOff: Sep 8 00:40:04.160285 kubelet[1726]: maxContainerRestartPeriod: 5m0s Sep 8 00:40:04.160285 kubelet[1726]: enableControllerAttachDetach: true Sep 8 00:40:04.160285 kubelet[1726]: enableDebugFlagsHandler: true Sep 8 00:40:04.160285 kubelet[1726]: enableDebuggingHandlers: true Sep 8 00:40:04.160285 kubelet[1726]: enableProfilingHandler: true Sep 8 00:40:04.160285 kubelet[1726]: enableServer: true Sep 8 00:40:04.160285 kubelet[1726]: enableSystemLogHandler: true Sep 8 00:40:04.160285 kubelet[1726]: enableSystemLogQuery: false Sep 8 00:40:04.160285 kubelet[1726]: enforceNodeAllocatable: Sep 8 00:40:04.160285 kubelet[1726]: - pods Sep 8 00:40:04.160285 kubelet[1726]: eventBurst: 100 Sep 8 00:40:04.160285 kubelet[1726]: eventRecordQPS: 50 Sep 8 00:40:04.160285 kubelet[1726]: evictionHard: Sep 8 00:40:04.160285 kubelet[1726]: imagefs.available: 15% Sep 8 00:40:04.160285 kubelet[1726]: imagefs.inodesFree: 5% Sep 8 00:40:04.160285 kubelet[1726]: memory.available: 100Mi Sep 8 00:40:04.160285 kubelet[1726]: nodefs.available: 10% Sep 8 00:40:04.160285 kubelet[1726]: nodefs.inodesFree: 5% Sep 8 00:40:04.160285 kubelet[1726]: evictionPressureTransitionPeriod: 5m0s Sep 8 00:40:04.160285 kubelet[1726]: failCgroupV1: true Sep 8 00:40:04.160285 kubelet[1726]: failSwapOn: true Sep 8 00:40:04.160285 kubelet[1726]: fileCheckFrequency: 20s Sep 8 00:40:04.160285 kubelet[1726]: hairpinMode: promiscuous-bridge Sep 8 00:40:04.160285 kubelet[1726]: healthzBindAddress: 127.0.0.1 Sep 8 00:40:04.160285 kubelet[1726]: healthzPort: 10248 Sep 8 00:40:04.160285 kubelet[1726]: httpCheckFrequency: 20s Sep 8 00:40:04.160285 kubelet[1726]: imageGCHighThresholdPercent: 85 Sep 8 00:40:04.160285 kubelet[1726]: imageGCLowThresholdPercent: 80 Sep 8 00:40:04.160285 kubelet[1726]: imageMaximumGCAge: 0s Sep 8 00:40:04.160285 kubelet[1726]: imageMinimumGCAge: 2m0s Sep 8 00:40:04.160285 kubelet[1726]: imagePullCredentialsVerificationPolicy: NeverVerifyPreloadedImages Sep 8 00:40:04.160285 kubelet[1726]: iptablesDropBit: 15 Sep 8 00:40:04.160285 kubelet[1726]: iptablesMasqueradeBit: 14 Sep 8 00:40:04.160285 kubelet[1726]: kind: KubeletConfiguration Sep 8 00:40:04.160285 kubelet[1726]: kubeAPIBurst: 100 Sep 8 00:40:04.160285 kubelet[1726]: kubeAPIQPS: 50 Sep 8 00:40:04.160285 kubelet[1726]: localStorageCapacityIsolation: true Sep 8 00:40:04.160285 kubelet[1726]: logging: Sep 8 00:40:04.160285 kubelet[1726]: flushFrequency: 5s Sep 8 00:40:04.160285 kubelet[1726]: format: text Sep 8 00:40:04.160285 kubelet[1726]: options: Sep 8 00:40:04.160285 kubelet[1726]: json: Sep 8 00:40:04.160285 kubelet[1726]: infoBufferSize: "0" Sep 8 00:40:04.160285 kubelet[1726]: text: Sep 8 00:40:04.160285 kubelet[1726]: infoBufferSize: "0" Sep 8 00:40:04.161625 kubelet[1726]: verbosity: 0 Sep 8 00:40:04.161625 kubelet[1726]: makeIPTablesUtilChains: true Sep 8 00:40:04.161625 kubelet[1726]: maxOpenFiles: 1000000 Sep 8 00:40:04.161625 kubelet[1726]: maxPods: 110 Sep 8 00:40:04.161625 kubelet[1726]: memoryManagerPolicy: None Sep 8 00:40:04.161625 kubelet[1726]: memoryReservationPolicy: None Sep 8 00:40:04.161625 kubelet[1726]: memorySwap: {} Sep 8 00:40:04.161625 kubelet[1726]: mergeDefaultEvictionSettings: false Sep 8 00:40:04.161625 kubelet[1726]: nodeLeaseDurationSeconds: 40 Sep 8 00:40:04.161625 kubelet[1726]: nodeStatusMaxImages: 50 Sep 8 00:40:04.161625 kubelet[1726]: nodeStatusReportFrequency: 5m0s Sep 8 00:40:04.161625 kubelet[1726]: nodeStatusUpdateFrequency: 10s Sep 8 00:40:04.161625 kubelet[1726]: oomScoreAdj: -999 Sep 8 00:40:04.161625 kubelet[1726]: podLogsDir: /var/log/pods Sep 8 00:40:04.161625 kubelet[1726]: podPidsLimit: -1 Sep 8 00:40:04.161625 kubelet[1726]: port: 10250 Sep 8 00:40:04.161625 kubelet[1726]: registerNode: true Sep 8 00:40:04.161625 kubelet[1726]: registryBurst: 10 Sep 8 00:40:04.161625 kubelet[1726]: registryPullQPS: 5 Sep 8 00:40:04.161625 kubelet[1726]: resolvConf: /run/systemd/resolve/resolv.conf Sep 8 00:40:04.161625 kubelet[1726]: rotateCertificates: true Sep 8 00:40:04.161625 kubelet[1726]: runtimeRequestTimeout: 2m0s Sep 8 00:40:04.161625 kubelet[1726]: seccompDefault: false Sep 8 00:40:04.161625 kubelet[1726]: serializeImagePulls: true Sep 8 00:40:04.161625 kubelet[1726]: shutdownGracePeriod: 0s Sep 8 00:40:04.161625 kubelet[1726]: shutdownGracePeriodCriticalPods: 0s Sep 8 00:40:04.161625 kubelet[1726]: staticPodPath: /etc/kubernetes/manifests Sep 8 00:40:04.161625 kubelet[1726]: streamingConnectionIdleTimeout: 4h0m0s Sep 8 00:40:04.161625 kubelet[1726]: syncFrequency: 1m0s Sep 8 00:40:04.161625 kubelet[1726]: topologyManagerPolicy: none Sep 8 00:40:04.161625 kubelet[1726]: topologyManagerScope: container Sep 8 00:40:04.161625 kubelet[1726]: volumePluginDir: /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ Sep 8 00:40:04.161625 kubelet[1726]: volumeStatsAggPeriod: 1m0s Sep 8 00:40:04.161625 kubelet[1726]: > Sep 8 00:40:04.301423 kubelet[1726]: I0908 00:40:04.301283 1726 server.go:545] "Kubelet version" kubeletVersion="v1.37.0" Sep 8 00:40:04.301423 kubelet[1726]: I0908 00:40:04.301346 1726 server.go:547] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 8 00:40:04.303143 kubelet[1726]: I0908 00:40:04.302825 1726 watchdog_linux.go:94] "Systemd watchdog is not enabled" Sep 8 00:40:04.303143 kubelet[1726]: I0908 00:40:04.303123 1726 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 8 00:40:04.304045 kubelet[1726]: I0908 00:40:04.303906 1726 server.go:1036] "Client rotation is on, will bootstrap in background" Sep 8 00:40:04.311899 kubelet[1726]: I0908 00:40:04.311583 1726 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 8 00:40:04.317241 kubelet[1726]: E0908 00:40:04.317179 1726 remote_runtime.go:1359] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Sep 8 00:40:04.317241 kubelet[1726]: I0908 00:40:04.317270 1726 server.go:1479] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Sep 8 00:40:04.333717 kubelet[1726]: I0908 00:40:04.333407 1726 server.go:858] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Sep 8 00:40:04.340372 kubelet[1726]: I0908 00:40:04.339978 1726 container_manager_linux.go:273] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 8 00:40:04.340552 kubelet[1726]: I0908 00:40:04.340209 1726 container_manager_linux.go:278] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.145","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"MemoryReservationPolicy":"None","MemoryThrottlingFactor":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 8 00:40:04.341280 kubelet[1726]: I0908 00:40:04.340564 1726 topology_manager.go:183] "Creating topology manager with none policy" Sep 8 00:40:04.341280 kubelet[1726]: I0908 00:40:04.340577 1726 container_manager_linux.go:310] "Creating device plugin manager" Sep 8 00:40:04.341280 kubelet[1726]: I0908 00:40:04.341265 1726 container_manager_linux.go:319] "Creating Dynamic Resource Allocation (DRA) manager" Sep 8 00:40:04.393889 kubelet[1726]: I0908 00:40:04.393820 1726 state_mem.go:45] "Initialized" logger="CPUManager state memory" Sep 8 00:40:04.394282 kubelet[1726]: I0908 00:40:04.394150 1726 kubelet.go:512] "Attempting to sync node with API server" Sep 8 00:40:04.394282 kubelet[1726]: I0908 00:40:04.394179 1726 kubelet.go:389] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 8 00:40:04.394282 kubelet[1726]: I0908 00:40:04.394214 1726 kubelet.go:400] "Adding apiserver pod source" Sep 8 00:40:04.394282 kubelet[1726]: I0908 00:40:04.394230 1726 apiserver.go:41] "Waiting for node sync before watching apiserver pods" Sep 8 00:40:04.394946 kubelet[1726]: E0908 00:40:04.394710 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:04.394946 kubelet[1726]: E0908 00:40:04.394752 1726 file.go:97] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:04.401813 kubelet[1726]: I0908 00:40:04.401623 1726 kuberuntime_manager.go:315] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Sep 8 00:40:04.404466 kubelet[1726]: I0908 00:40:04.404421 1726 kubelet.go:1011] "ClusterTrustBundle informer will be started eventually once a trust bundle is requested" Sep 8 00:40:04.404869 kubelet[1726]: W0908 00:40:04.404778 1726 probe.go:275] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 8 00:40:04.405624 kubelet[1726]: I0908 00:40:04.405342 1726 podcertificatemanager.go:340] "podcertificate.IssuingManager starting up" Sep 8 00:40:04.412032 kubelet[1726]: I0908 00:40:04.410396 1726 server.go:1336] "Started kubelet" Sep 8 00:40:04.412032 kubelet[1726]: I0908 00:40:04.410684 1726 server.go:196] "Starting to listen" address="0.0.0.0" port=10250 Sep 8 00:40:04.412032 kubelet[1726]: I0908 00:40:04.411819 1726 server.go:293] "Starting to serve the pods API" endpoint="unix:/var/lib/kubelet/pods-api/pods-api.sock" Sep 8 00:40:04.412032 kubelet[1726]: I0908 00:40:04.411965 1726 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 8 00:40:04.412283 kubelet[1726]: I0908 00:40:04.412239 1726 server_v1.go:49] "podresources" method="list" useActivePods=true Sep 8 00:40:04.412371 kubelet[1726]: I0908 00:40:04.412328 1726 server.go:365] "Adding debug handlers to kubelet server" Sep 8 00:40:04.412371 kubelet[1726]: I0908 00:40:04.412368 1726 server.go:267] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 8 00:40:04.412671 kubelet[1726]: I0908 00:40:04.412580 1726 fs_resource_analyzer.go:69] "Starting FS ResourceAnalyzer" Sep 8 00:40:04.412671 kubelet[1726]: I0908 00:40:04.412625 1726 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 8 00:40:04.421525 kubelet[1726]: I0908 00:40:04.420323 1726 volume_manager.go:331] "Starting Kubelet Volume Manager" Sep 8 00:40:04.421525 kubelet[1726]: I0908 00:40:04.420474 1726 tlsconfig.go:243] "Starting DynamicServingCertificateController" Sep 8 00:40:04.422619 kubelet[1726]: I0908 00:40:04.422536 1726 reconciler.go:29] "Reconciler: start to sync state" Sep 8 00:40:04.422619 kubelet[1726]: I0908 00:40:04.422609 1726 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Sep 8 00:40:04.423157 kubelet[1726]: E0908 00:40:04.423068 1726 kubelet_node_status.go:396] "Error getting the current node from lister" err="node \"10.0.0.145\" not found" Sep 8 00:40:04.424373 kubelet[1726]: I0908 00:40:04.423928 1726 factory.go:222] Registration of the systemd container factory successfully Sep 8 00:40:04.424373 kubelet[1726]: E0908 00:40:04.424014 1726 kubelet.go:3596] "Unable to register mirror pod because node is not registered yet" err="node \"10.0.0.145\" not found" node="10.0.0.145" Sep 8 00:40:04.424373 kubelet[1726]: E0908 00:40:04.424275 1726 kubelet.go:1754] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 8 00:40:04.424373 kubelet[1726]: I0908 00:40:04.424375 1726 factory.go:220] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 8 00:40:04.427620 kubelet[1726]: I0908 00:40:04.427499 1726 factory.go:222] Registration of the containerd container factory successfully Sep 8 00:40:04.441450 kubelet[1726]: E0908 00:40:04.439284 1726 nodelease.go:50] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.145\" not found" node="10.0.0.145" Sep 8 00:40:04.451202 kubelet[1726]: I0908 00:40:04.451118 1726 cpu_manager.go:239] "Starting" policy="none" Sep 8 00:40:04.451202 kubelet[1726]: I0908 00:40:04.451150 1726 cpu_manager.go:240] "Reconciling" reconcilePeriod="10s" Sep 8 00:40:04.451202 kubelet[1726]: I0908 00:40:04.451181 1726 state_mem.go:45] "Initialized" logger="CPUManager state checkpoint.CPUManager state memory" Sep 8 00:40:04.451202 kubelet[1726]: I0908 00:40:04.451192 1726 state_checkpoint.go:191] "trying to load v4 CPU manager checkpoint" logger="CPUManager state checkpoint" Sep 8 00:40:04.457713 kubelet[1726]: I0908 00:40:04.456261 1726 policy_none.go:51] "Start" Sep 8 00:40:04.457713 kubelet[1726]: I0908 00:40:04.456362 1726 memory_manager.go:209] "Starting memorymanager" policy="None" Sep 8 00:40:04.457713 kubelet[1726]: I0908 00:40:04.456386 1726 state_mem.go:39] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Sep 8 00:40:04.460514 kubelet[1726]: I0908 00:40:04.460144 1726 policy_none.go:47] "Start" Sep 8 00:40:04.467149 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 8 00:40:04.486785 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 8 00:40:04.498385 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 8 00:40:04.507163 kubelet[1726]: E0908 00:40:04.507096 1726 manager.go:550] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 8 00:40:04.507467 kubelet[1726]: I0908 00:40:04.507417 1726 eviction_manager.go:193] "Eviction manager: starting control loop" Sep 8 00:40:04.507597 kubelet[1726]: I0908 00:40:04.507469 1726 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 8 00:40:04.508019 kubelet[1726]: I0908 00:40:04.507956 1726 plugin_manager.go:121] "Starting Kubelet Plugin Manager" Sep 8 00:40:04.510373 kubelet[1726]: E0908 00:40:04.510310 1726 eviction_manager.go:280] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 8 00:40:04.510450 kubelet[1726]: E0908 00:40:04.510385 1726 eviction_manager.go:305] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.145\" not found" Sep 8 00:40:04.524293 kubelet[1726]: E0908 00:40:04.524177 1726 kubelet.go:3596] "Unable to register mirror pod because node is not registered yet" err="node \"10.0.0.145\" not found" node="10.0.0.145" Sep 8 00:40:04.524919 kubelet[1726]: I0908 00:40:04.524868 1726 kubelet_network_linux.go:53] "Initialized iptables rules." protocol="IPv4" Sep 8 00:40:04.526450 kubelet[1726]: I0908 00:40:04.526394 1726 kubelet_network_linux.go:53] "Initialized iptables rules." protocol="IPv6" Sep 8 00:40:04.526450 kubelet[1726]: I0908 00:40:04.526425 1726 status_manager.go:283] "Starting to sync pod status with apiserver" Sep 8 00:40:04.526580 kubelet[1726]: I0908 00:40:04.526461 1726 kubelet.go:2673] "Starting kubelet main sync loop" Sep 8 00:40:04.526580 kubelet[1726]: E0908 00:40:04.526504 1726 kubelet.go:2697] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 8 00:40:04.609505 kubelet[1726]: I0908 00:40:04.609232 1726 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.145" Sep 8 00:40:04.613780 sudo[1588]: pam_unix(sudo:session): session closed for user root Sep 8 00:40:04.617081 sshd[1585]: pam_unix(sshd:session): session closed for user core Sep 8 00:40:04.620530 kubelet[1726]: I0908 00:40:04.620397 1726 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.145" Sep 8 00:40:04.623367 systemd[1]: sshd@4-10.0.0.145:22-10.0.0.1:44502.service: Deactivated successfully. Sep 8 00:40:04.626033 systemd[1]: session-5.scope: Deactivated successfully. Sep 8 00:40:04.628421 systemd-logind[1440]: Session 5 logged out. Waiting for processes to exit. Sep 8 00:40:04.631988 systemd-logind[1440]: Removed session 5. Sep 8 00:40:04.740611 kubelet[1726]: I0908 00:40:04.740547 1726 kuberuntime_manager.go:2315] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 8 00:40:04.741277 containerd[1453]: time="2026-09-08T00:40:04.741224896Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 8 00:40:04.741742 kubelet[1726]: I0908 00:40:04.741499 1726 kubelet_network.go:48] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 8 00:40:05.306925 kubelet[1726]: I0908 00:40:05.306805 1726 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 8 00:40:05.307500 kubelet[1726]: I0908 00:40:05.307442 1726 reflector.go:664] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:178" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:178: Unexpected watch close - watch lasted less than a second and no items received" Sep 8 00:40:05.307586 kubelet[1726]: I0908 00:40:05.307519 1726 reflector.go:664] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:178" type="*v1.PodCertificateRequest" err="very short watch: k8s.io/client-go/informers/factory.go:178: Unexpected watch close - watch lasted less than a second and no items received" Sep 8 00:40:05.307586 kubelet[1726]: I0908 00:40:05.307555 1726 reflector.go:664] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:178" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:178: Unexpected watch close - watch lasted less than a second and no items received" Sep 8 00:40:05.307721 kubelet[1726]: I0908 00:40:05.307607 1726 reflector.go:664] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:178" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:178: Unexpected watch close - watch lasted less than a second and no items received" Sep 8 00:40:05.395997 kubelet[1726]: E0908 00:40:05.395894 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:05.395997 kubelet[1726]: I0908 00:40:05.395955 1726 apiserver.go:51] "Watching apiserver" Sep 8 00:40:05.416510 kubelet[1726]: I0908 00:40:05.416368 1726 server.go:177] "Pod update broadcasted" podUID="5bef8910-6dae-4338-92f3-b1c61984ef7c" type="ADDED" Sep 8 00:40:05.420620 kubelet[1726]: I0908 00:40:05.419822 1726 server.go:177] "Pod update broadcasted" podUID="94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db" type="ADDED" Sep 8 00:40:05.424024 kubelet[1726]: I0908 00:40:05.423773 1726 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Sep 8 00:40:05.430088 kubelet[1726]: I0908 00:40:05.429865 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/5bef8910-6dae-4338-92f3-b1c61984ef7c-xtables-lock\") pod \"kube-flannel-ds-4cp7b\" (UID: \"5bef8910-6dae-4338-92f3-b1c61984ef7c\") " pod="kube-flannel/kube-flannel-ds-4cp7b" Sep 8 00:40:05.430088 kubelet[1726]: I0908 00:40:05.430022 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db-kube-proxy\") pod \"kube-proxy-hv7zm\" (UID: \"94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db\") " pod="kube-system/kube-proxy-hv7zm" Sep 8 00:40:05.430523 kubelet[1726]: I0908 00:40:05.430085 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db-xtables-lock\") pod \"kube-proxy-hv7zm\" (UID: \"94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db\") " pod="kube-system/kube-proxy-hv7zm" Sep 8 00:40:05.430523 kubelet[1726]: I0908 00:40:05.430369 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db-lib-modules\") pod \"kube-proxy-hv7zm\" (UID: \"94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db\") " pod="kube-system/kube-proxy-hv7zm" Sep 8 00:40:05.430523 kubelet[1726]: I0908 00:40:05.430390 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/5bef8910-6dae-4338-92f3-b1c61984ef7c-run\") pod \"kube-flannel-ds-4cp7b\" (UID: \"5bef8910-6dae-4338-92f3-b1c61984ef7c\") " pod="kube-flannel/kube-flannel-ds-4cp7b" Sep 8 00:40:05.430523 kubelet[1726]: I0908 00:40:05.430407 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/5bef8910-6dae-4338-92f3-b1c61984ef7c-cni\") pod \"kube-flannel-ds-4cp7b\" (UID: \"5bef8910-6dae-4338-92f3-b1c61984ef7c\") " pod="kube-flannel/kube-flannel-ds-4cp7b" Sep 8 00:40:05.430523 kubelet[1726]: I0908 00:40:05.430476 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tlgp6\" (UniqueName: \"kubernetes.io/projected/5bef8910-6dae-4338-92f3-b1c61984ef7c-kube-api-access-tlgp6\") pod \"kube-flannel-ds-4cp7b\" (UID: \"5bef8910-6dae-4338-92f3-b1c61984ef7c\") " pod="kube-flannel/kube-flannel-ds-4cp7b" Sep 8 00:40:05.430833 kubelet[1726]: I0908 00:40:05.430497 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4qs9q\" (UniqueName: \"kubernetes.io/projected/94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db-kube-api-access-4qs9q\") pod \"kube-proxy-hv7zm\" (UID: \"94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db\") " pod="kube-system/kube-proxy-hv7zm" Sep 8 00:40:05.430833 kubelet[1726]: I0908 00:40:05.430516 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/5bef8910-6dae-4338-92f3-b1c61984ef7c-cni-plugin\") pod \"kube-flannel-ds-4cp7b\" (UID: \"5bef8910-6dae-4338-92f3-b1c61984ef7c\") " pod="kube-flannel/kube-flannel-ds-4cp7b" Sep 8 00:40:05.430833 kubelet[1726]: I0908 00:40:05.430572 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/5bef8910-6dae-4338-92f3-b1c61984ef7c-flannel-cfg\") pod \"kube-flannel-ds-4cp7b\" (UID: \"5bef8910-6dae-4338-92f3-b1c61984ef7c\") " pod="kube-flannel/kube-flannel-ds-4cp7b" Sep 8 00:40:05.435259 systemd[1]: Created slice kubepods-burstable-pod5bef8910_6dae_4338_92f3_b1c61984ef7c.slice - libcontainer container kubepods-burstable-pod5bef8910_6dae_4338_92f3_b1c61984ef7c.slice. Sep 8 00:40:05.459590 systemd[1]: Created slice kubepods-besteffort-pod94444fbe_bd69_4a7f_9d9a_36cdd2d9a1db.slice - libcontainer container kubepods-besteffort-pod94444fbe_bd69_4a7f_9d9a_36cdd2d9a1db.slice. Sep 8 00:40:05.764005 kubelet[1726]: E0908 00:40:05.763583 1726 dns.go:154] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 8 00:40:05.765285 containerd[1453]: time="2026-09-08T00:40:05.765106569Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4cp7b,Uid:5bef8910-6dae-4338-92f3-b1c61984ef7c,Namespace:kube-flannel,Attempt:0,}" Sep 8 00:40:05.780969 kubelet[1726]: E0908 00:40:05.780591 1726 dns.go:154] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 8 00:40:05.781576 containerd[1453]: time="2026-09-08T00:40:05.781502926Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-hv7zm,Uid:94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db,Namespace:kube-system,Attempt:0,}" Sep 8 00:40:06.278725 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount91718135.mount: Deactivated successfully. Sep 8 00:40:06.288422 containerd[1453]: time="2026-09-08T00:40:06.288296918Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 00:40:06.290818 containerd[1453]: time="2026-09-08T00:40:06.290730644Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 00:40:06.291523 containerd[1453]: time="2026-09-08T00:40:06.291307869Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312116" Sep 8 00:40:06.292881 containerd[1453]: time="2026-09-08T00:40:06.292813286Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 8 00:40:06.294110 containerd[1453]: time="2026-09-08T00:40:06.294024401Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 00:40:06.297447 containerd[1453]: time="2026-09-08T00:40:06.297361470Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 8 00:40:06.298574 containerd[1453]: time="2026-09-08T00:40:06.298500077Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 516.815524ms" Sep 8 00:40:06.300140 containerd[1453]: time="2026-09-08T00:40:06.300072865Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 534.727074ms" Sep 8 00:40:06.396488 kubelet[1726]: E0908 00:40:06.396016 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:06.432425 containerd[1453]: time="2026-09-08T00:40:06.432196053Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 8 00:40:06.432425 containerd[1453]: time="2026-09-08T00:40:06.432312563Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 8 00:40:06.432425 containerd[1453]: time="2026-09-08T00:40:06.432333476Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:06.433158 containerd[1453]: time="2026-09-08T00:40:06.432853508Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:06.433586 containerd[1453]: time="2026-09-08T00:40:06.432965535Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 8 00:40:06.433586 containerd[1453]: time="2026-09-08T00:40:06.433096376Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 8 00:40:06.433586 containerd[1453]: time="2026-09-08T00:40:06.433118640Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:06.433586 containerd[1453]: time="2026-09-08T00:40:06.433219894Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:06.493127 systemd[1]: Started cri-containerd-04c83c3f47c343768fb70847c63598b65546832146ca23b4f2d629fe7c5316dc.scope - libcontainer container 04c83c3f47c343768fb70847c63598b65546832146ca23b4f2d629fe7c5316dc. Sep 8 00:40:06.497915 systemd[1]: Started cri-containerd-064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397.scope - libcontainer container 064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397. Sep 8 00:40:06.545970 containerd[1453]: time="2026-09-08T00:40:06.544447617Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-hv7zm,Uid:94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db,Namespace:kube-system,Attempt:0,} returns sandbox id \"04c83c3f47c343768fb70847c63598b65546832146ca23b4f2d629fe7c5316dc\"" Sep 8 00:40:06.549581 kubelet[1726]: I0908 00:40:06.549514 1726 server.go:177] "Pod update broadcasted" podUID="94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db" type="MODIFIED" Sep 8 00:40:06.551783 kubelet[1726]: E0908 00:40:06.550195 1726 dns.go:154] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 8 00:40:06.568323 containerd[1453]: time="2026-09-08T00:40:06.568246202Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.37.0\"" Sep 8 00:40:06.576244 containerd[1453]: time="2026-09-08T00:40:06.576059568Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4cp7b,Uid:5bef8910-6dae-4338-92f3-b1c61984ef7c,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397\"" Sep 8 00:40:06.578797 kubelet[1726]: E0908 00:40:06.578543 1726 dns.go:154] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 8 00:40:06.578991 kubelet[1726]: I0908 00:40:06.578942 1726 server.go:177] "Pod update broadcasted" podUID="5bef8910-6dae-4338-92f3-b1c61984ef7c" type="MODIFIED" Sep 8 00:40:07.397386 kubelet[1726]: E0908 00:40:07.397237 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:07.535234 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount728145751.mount: Deactivated successfully. Sep 8 00:40:07.859979 containerd[1453]: time="2026-09-08T00:40:07.859576759Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.37.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:07.860373 containerd[1453]: time="2026-09-08T00:40:07.860322487Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.37.0: active requests=0, bytes read=30201653" Sep 8 00:40:07.862145 containerd[1453]: time="2026-09-08T00:40:07.862000599Z" level=info msg="ImageCreate event name:\"sha256:d6a28daf3e6b0ac88d2c74d715a4a156a33c1acef57fe89bcd334c47532949e7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:07.864800 containerd[1453]: time="2026-09-08T00:40:07.864558636Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:77bf2b08ff8ac1a4a446cffaa85e79e87379c8cb54fcf60f2cb6c62c966829c8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:07.866224 containerd[1453]: time="2026-09-08T00:40:07.866136999Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.37.0\" with image id \"sha256:d6a28daf3e6b0ac88d2c74d715a4a156a33c1acef57fe89bcd334c47532949e7\", repo tag \"registry.k8s.io/kube-proxy:v1.37.0\", repo digest \"registry.k8s.io/kube-proxy@sha256:77bf2b08ff8ac1a4a446cffaa85e79e87379c8cb54fcf60f2cb6c62c966829c8\", size \"30200433\" in 1.297799885s" Sep 8 00:40:07.866224 containerd[1453]: time="2026-09-08T00:40:07.866210732Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.37.0\" returns image reference \"sha256:d6a28daf3e6b0ac88d2c74d715a4a156a33c1acef57fe89bcd334c47532949e7\"" Sep 8 00:40:07.868594 containerd[1453]: time="2026-09-08T00:40:07.868410728Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 8 00:40:07.873234 containerd[1453]: time="2026-09-08T00:40:07.873135990Z" level=info msg="CreateContainer within sandbox \"04c83c3f47c343768fb70847c63598b65546832146ca23b4f2d629fe7c5316dc\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 8 00:40:07.898207 containerd[1453]: time="2026-09-08T00:40:07.898085012Z" level=info msg="CreateContainer within sandbox \"04c83c3f47c343768fb70847c63598b65546832146ca23b4f2d629fe7c5316dc\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"cc541e2fdd40a3ee581ddc6cec317671af56c26848e1b5908f8cf4b3a147a525\"" Sep 8 00:40:07.899339 containerd[1453]: time="2026-09-08T00:40:07.899249604Z" level=info msg="StartContainer for \"cc541e2fdd40a3ee581ddc6cec317671af56c26848e1b5908f8cf4b3a147a525\"" Sep 8 00:40:08.009617 systemd[1]: Started cri-containerd-cc541e2fdd40a3ee581ddc6cec317671af56c26848e1b5908f8cf4b3a147a525.scope - libcontainer container cc541e2fdd40a3ee581ddc6cec317671af56c26848e1b5908f8cf4b3a147a525. Sep 8 00:40:08.062621 containerd[1453]: time="2026-09-08T00:40:08.062500501Z" level=info msg="StartContainer for \"cc541e2fdd40a3ee581ddc6cec317671af56c26848e1b5908f8cf4b3a147a525\" returns successfully" Sep 8 00:40:08.074755 kubelet[1726]: I0908 00:40:08.074342 1726 server.go:177] "Pod update broadcasted" podUID="94444fbe-bd69-4a7f-9d9a-36cdd2d9a1db" type="MODIFIED" Sep 8 00:40:08.399005 kubelet[1726]: E0908 00:40:08.398914 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:09.400001 kubelet[1726]: E0908 00:40:09.399915 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:09.656490 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1083674922.mount: Deactivated successfully. Sep 8 00:40:09.704684 containerd[1453]: time="2026-09-08T00:40:09.704442151Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:09.705611 containerd[1453]: time="2026-09-08T00:40:09.705491500Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Sep 8 00:40:09.707008 containerd[1453]: time="2026-09-08T00:40:09.706879875Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:09.713318 containerd[1453]: time="2026-09-08T00:40:09.713193264Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:09.714272 containerd[1453]: time="2026-09-08T00:40:09.714183518Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 1.845672472s" Sep 8 00:40:09.714272 containerd[1453]: time="2026-09-08T00:40:09.714233671Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Sep 8 00:40:09.718312 containerd[1453]: time="2026-09-08T00:40:09.718237244Z" level=info msg="CreateContainer within sandbox \"064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 8 00:40:09.738740 containerd[1453]: time="2026-09-08T00:40:09.738555686Z" level=info msg="CreateContainer within sandbox \"064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5\"" Sep 8 00:40:09.739863 containerd[1453]: time="2026-09-08T00:40:09.739698222Z" level=info msg="StartContainer for \"c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5\"" Sep 8 00:40:09.778253 systemd[1]: Started cri-containerd-c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5.scope - libcontainer container c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5. Sep 8 00:40:09.809841 systemd[1]: cri-containerd-c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5.scope: Deactivated successfully. Sep 8 00:40:09.811544 containerd[1453]: time="2026-09-08T00:40:09.811129198Z" level=info msg="StartContainer for \"c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5\" returns successfully" Sep 8 00:40:09.816983 kubelet[1726]: I0908 00:40:09.816863 1726 server.go:177] "Pod update broadcasted" podUID="5bef8910-6dae-4338-92f3-b1c61984ef7c" type="MODIFIED" Sep 8 00:40:09.841237 kubelet[1726]: I0908 00:40:09.840785 1726 pod_startup_latency_tracker.go:144] "Observed pod startup duration" pod="kube-system/kube-proxy-hv7zm" podStartSLOduration=4.53583181 podStartE2EDuration="5.840617753s" totalImagesPullingTime="1.304785943s" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-09-08 00:40:04 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-08 00:40:08.097900411 +0000 UTC m=+3.994688200" watchObservedRunningTime="2026-09-08 00:40:09.840617753 +0000 UTC m=+5.737405541" Sep 8 00:40:10.000948 containerd[1453]: time="2026-09-08T00:40:09.999964257Z" level=info msg="shim disconnected" id=c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5 namespace=k8s.io Sep 8 00:40:10.000948 containerd[1453]: time="2026-09-08T00:40:10.000123026Z" level=warning msg="cleaning up after shim disconnected" id=c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5 namespace=k8s.io Sep 8 00:40:10.000948 containerd[1453]: time="2026-09-08T00:40:10.000140086Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 8 00:40:10.401441 kubelet[1726]: E0908 00:40:10.401078 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:10.562782 kubelet[1726]: I0908 00:40:10.562742 1726 server.go:177] "Pod update broadcasted" podUID="5bef8910-6dae-4338-92f3-b1c61984ef7c" type="MODIFIED" Sep 8 00:40:10.563100 kubelet[1726]: E0908 00:40:10.563058 1726 dns.go:154] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 8 00:40:10.564017 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c71d04d2df142ce93196beb42639649629162bd025099f701511bc4b53c57aa5-rootfs.mount: Deactivated successfully. Sep 8 00:40:10.566113 containerd[1453]: time="2026-09-08T00:40:10.566051322Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 8 00:40:11.402967 kubelet[1726]: E0908 00:40:11.402779 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:12.403189 kubelet[1726]: E0908 00:40:12.403058 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:13.403445 kubelet[1726]: E0908 00:40:13.403368 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:13.872987 containerd[1453]: time="2026-09-08T00:40:13.872617924Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:13.873987 containerd[1453]: time="2026-09-08T00:40:13.873841286Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Sep 8 00:40:13.875505 containerd[1453]: time="2026-09-08T00:40:13.875374685Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:13.882005 containerd[1453]: time="2026-09-08T00:40:13.881893527Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:13.884315 containerd[1453]: time="2026-09-08T00:40:13.884238736Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32996046\" in 3.31809159s" Sep 8 00:40:13.884315 containerd[1453]: time="2026-09-08T00:40:13.884314497Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Sep 8 00:40:13.887979 containerd[1453]: time="2026-09-08T00:40:13.887934097Z" level=info msg="CreateContainer within sandbox \"064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 8 00:40:13.902307 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount406838536.mount: Deactivated successfully. Sep 8 00:40:13.906708 containerd[1453]: time="2026-09-08T00:40:13.906615169Z" level=info msg="CreateContainer within sandbox \"064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d\"" Sep 8 00:40:13.907506 containerd[1453]: time="2026-09-08T00:40:13.907390748Z" level=info msg="StartContainer for \"3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d\"" Sep 8 00:40:13.961985 systemd[1]: Started cri-containerd-3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d.scope - libcontainer container 3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d. Sep 8 00:40:14.001160 systemd[1]: cri-containerd-3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d.scope: Deactivated successfully. Sep 8 00:40:14.003715 containerd[1453]: time="2026-09-08T00:40:14.003628590Z" level=info msg="StartContainer for \"3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d\" returns successfully" Sep 8 00:40:14.009860 kubelet[1726]: I0908 00:40:14.009783 1726 server.go:177] "Pod update broadcasted" podUID="5bef8910-6dae-4338-92f3-b1c61984ef7c" type="MODIFIED" Sep 8 00:40:14.033600 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d-rootfs.mount: Deactivated successfully. Sep 8 00:40:14.043690 kubelet[1726]: I0908 00:40:14.043619 1726 kubelet_node_status.go:431] "Fast updating node status as it just became ready" Sep 8 00:40:14.113321 containerd[1453]: time="2026-09-08T00:40:14.113144326Z" level=info msg="shim disconnected" id=3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d namespace=k8s.io Sep 8 00:40:14.113321 containerd[1453]: time="2026-09-08T00:40:14.113263555Z" level=warning msg="cleaning up after shim disconnected" id=3e0b9be7308e668524acde2ffcbeec8992f2ffa3689e3c062b11e60d00dbb17d namespace=k8s.io Sep 8 00:40:14.113321 containerd[1453]: time="2026-09-08T00:40:14.113284161Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 8 00:40:14.404591 kubelet[1726]: E0908 00:40:14.404514 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:14.575466 kubelet[1726]: I0908 00:40:14.575362 1726 server.go:177] "Pod update broadcasted" podUID="5bef8910-6dae-4338-92f3-b1c61984ef7c" type="MODIFIED" Sep 8 00:40:14.575694 kubelet[1726]: E0908 00:40:14.575675 1726 dns.go:154] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 8 00:40:14.578995 containerd[1453]: time="2026-09-08T00:40:14.578922370Z" level=info msg="CreateContainer within sandbox \"064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 8 00:40:14.597886 containerd[1453]: time="2026-09-08T00:40:14.597814533Z" level=info msg="CreateContainer within sandbox \"064f6a61acf13574f52c142fa3b2c67d96e7bc1dfffaa33460f0b87bcae95397\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"827afcc4629e7c126ab1e391a33d4ce693e4b96c1dd778c043931ca456ccf610\"" Sep 8 00:40:14.598619 containerd[1453]: time="2026-09-08T00:40:14.598597374Z" level=info msg="StartContainer for \"827afcc4629e7c126ab1e391a33d4ce693e4b96c1dd778c043931ca456ccf610\"" Sep 8 00:40:14.649034 systemd[1]: Started cri-containerd-827afcc4629e7c126ab1e391a33d4ce693e4b96c1dd778c043931ca456ccf610.scope - libcontainer container 827afcc4629e7c126ab1e391a33d4ce693e4b96c1dd778c043931ca456ccf610. Sep 8 00:40:14.678537 containerd[1453]: time="2026-09-08T00:40:14.678404150Z" level=info msg="StartContainer for \"827afcc4629e7c126ab1e391a33d4ce693e4b96c1dd778c043931ca456ccf610\" returns successfully" Sep 8 00:40:14.682431 kubelet[1726]: I0908 00:40:14.682374 1726 server.go:177] "Pod update broadcasted" podUID="5bef8910-6dae-4338-92f3-b1c61984ef7c" type="MODIFIED" Sep 8 00:40:15.404890 kubelet[1726]: E0908 00:40:15.404802 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:15.591039 kubelet[1726]: I0908 00:40:15.590887 1726 pod_startup_latency_tracker.go:144] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-4cp7b" podStartSLOduration=5.13500367 podStartE2EDuration="11.590866702s" totalImagesPullingTime="6.455863032s" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-09-08 00:40:04 +0000 UTC" imagePullSessionsCount=2 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-08 00:40:14.699741736 +0000 UTC m=+10.596529522" watchObservedRunningTime="2026-09-08 00:40:15.590866702 +0000 UTC m=+11.487654473" Sep 8 00:40:15.594267 kubelet[1726]: I0908 00:40:15.594178 1726 server.go:177] "Pod update broadcasted" podUID="407e6e93-0837-46c0-a580-598c1d4c9d54" type="ADDED" Sep 8 00:40:15.612568 systemd[1]: Created slice kubepods-besteffort-pod407e6e93_0837_46c0_a580_598c1d4c9d54.slice - libcontainer container kubepods-besteffort-pod407e6e93_0837_46c0_a580_598c1d4c9d54.slice. Sep 8 00:40:15.634123 kubelet[1726]: I0908 00:40:15.634060 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ld8gf\" (UniqueName: \"kubernetes.io/projected/407e6e93-0837-46c0-a580-598c1d4c9d54-kube-api-access-ld8gf\") pod \"nginx-deployment-5b96b857d-9w76f\" (UID: \"407e6e93-0837-46c0-a580-598c1d4c9d54\") " pod="default/nginx-deployment-5b96b857d-9w76f" Sep 8 00:40:15.787390 systemd-networkd[1385]: flannel.1: Link UP Sep 8 00:40:15.787401 systemd-networkd[1385]: flannel.1: Gained carrier Sep 8 00:40:15.921421 containerd[1453]: time="2026-09-08T00:40:15.921357708Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-5b96b857d-9w76f,Uid:407e6e93-0837-46c0-a580-598c1d4c9d54,Namespace:default,Attempt:0,}" Sep 8 00:40:15.980926 systemd-networkd[1385]: cni0: Link UP Sep 8 00:40:15.980933 systemd-networkd[1385]: cni0: Gained carrier Sep 8 00:40:15.981888 systemd-networkd[1385]: cni0: Lost carrier Sep 8 00:40:15.992405 systemd-networkd[1385]: vethb4730ce1: Link UP Sep 8 00:40:15.994532 kernel: cni0: port 1(vethb4730ce1) entered blocking state Sep 8 00:40:15.994615 kernel: cni0: port 1(vethb4730ce1) entered disabled state Sep 8 00:40:15.994628 kernel: vethb4730ce1: entered allmulticast mode Sep 8 00:40:15.996992 kernel: vethb4730ce1: entered promiscuous mode Sep 8 00:40:15.997035 kernel: cni0: port 1(vethb4730ce1) entered blocking state Sep 8 00:40:15.998021 kernel: cni0: port 1(vethb4730ce1) entered forwarding state Sep 8 00:40:15.999188 kernel: cni0: port 1(vethb4730ce1) entered disabled state Sep 8 00:40:16.006920 kernel: cni0: port 1(vethb4730ce1) entered blocking state Sep 8 00:40:16.007011 kernel: cni0: port 1(vethb4730ce1) entered forwarding state Sep 8 00:40:16.007035 systemd-networkd[1385]: vethb4730ce1: Gained carrier Sep 8 00:40:16.007275 systemd-networkd[1385]: cni0: Gained carrier Sep 8 00:40:16.017066 containerd[1453]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000102950), "name":"cbr0", "type":"bridge"} Sep 8 00:40:16.017066 containerd[1453]: delegateAdd: netconf sent to delegate plugin: Sep 8 00:40:16.044686 containerd[1453]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2026-09-08T00:40:16.044392675Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 8 00:40:16.044686 containerd[1453]: time="2026-09-08T00:40:16.044452936Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 8 00:40:16.044686 containerd[1453]: time="2026-09-08T00:40:16.044462871Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:16.044938 containerd[1453]: time="2026-09-08T00:40:16.044537863Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:16.074051 systemd[1]: Started cri-containerd-cd2581283a4ae26948e8def46bf6f63f429d66ba0b93c419652ec5ad82b9860c.scope - libcontainer container cd2581283a4ae26948e8def46bf6f63f429d66ba0b93c419652ec5ad82b9860c. Sep 8 00:40:16.088426 systemd-resolved[1339]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 8 00:40:16.119364 containerd[1453]: time="2026-09-08T00:40:16.119300489Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-5b96b857d-9w76f,Uid:407e6e93-0837-46c0-a580-598c1d4c9d54,Namespace:default,Attempt:0,} returns sandbox id \"cd2581283a4ae26948e8def46bf6f63f429d66ba0b93c419652ec5ad82b9860c\"" Sep 8 00:40:16.120846 kubelet[1726]: I0908 00:40:16.120812 1726 server.go:177] "Pod update broadcasted" podUID="407e6e93-0837-46c0-a580-598c1d4c9d54" type="MODIFIED" Sep 8 00:40:16.121170 containerd[1453]: time="2026-09-08T00:40:16.121138381Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 8 00:40:16.405322 kubelet[1726]: E0908 00:40:16.405134 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:17.184196 systemd-networkd[1385]: cni0: Gained IPv6LL Sep 8 00:40:17.184665 systemd-networkd[1385]: vethb4730ce1: Gained IPv6LL Sep 8 00:40:17.405887 kubelet[1726]: E0908 00:40:17.405784 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:17.761024 systemd-networkd[1385]: flannel.1: Gained IPv6LL Sep 8 00:40:18.441489 kubelet[1726]: E0908 00:40:18.436707 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:18.953467 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2789834729.mount: Deactivated successfully. Sep 8 00:40:19.442279 kubelet[1726]: E0908 00:40:19.442130 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:19.899791 containerd[1453]: time="2026-09-08T00:40:19.899588224Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:19.900411 containerd[1453]: time="2026-09-08T00:40:19.900349408Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=67099919" Sep 8 00:40:19.901004 containerd[1453]: time="2026-09-08T00:40:19.900957054Z" level=info msg="ImageCreate event name:\"sha256:3c175c1aa411ed8265ccc495ebd34815c53bb937bc90a54ce24708a48ee5e0b7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:19.903600 containerd[1453]: time="2026-09-08T00:40:19.903556567Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:e68933b249335b5a57ee334d1d621e5e881d280ed29fdcadfbaa34b963a52a01\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:19.904569 containerd[1453]: time="2026-09-08T00:40:19.904530490Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:3c175c1aa411ed8265ccc495ebd34815c53bb937bc90a54ce24708a48ee5e0b7\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e68933b249335b5a57ee334d1d621e5e881d280ed29fdcadfbaa34b963a52a01\", size \"67099797\" in 3.783348928s" Sep 8 00:40:19.904665 containerd[1453]: time="2026-09-08T00:40:19.904575503Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:3c175c1aa411ed8265ccc495ebd34815c53bb937bc90a54ce24708a48ee5e0b7\"" Sep 8 00:40:19.907309 containerd[1453]: time="2026-09-08T00:40:19.907277414Z" level=info msg="CreateContainer within sandbox \"cd2581283a4ae26948e8def46bf6f63f429d66ba0b93c419652ec5ad82b9860c\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 8 00:40:19.920417 containerd[1453]: time="2026-09-08T00:40:19.920369338Z" level=info msg="CreateContainer within sandbox \"cd2581283a4ae26948e8def46bf6f63f429d66ba0b93c419652ec5ad82b9860c\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"5ad1dc1977e8569ad7c5c6ef7c5abbe3fabf2a3c39ee41315de60f47e943424f\"" Sep 8 00:40:19.921089 containerd[1453]: time="2026-09-08T00:40:19.921060088Z" level=info msg="StartContainer for \"5ad1dc1977e8569ad7c5c6ef7c5abbe3fabf2a3c39ee41315de60f47e943424f\"" Sep 8 00:40:20.013920 systemd[1]: Started cri-containerd-5ad1dc1977e8569ad7c5c6ef7c5abbe3fabf2a3c39ee41315de60f47e943424f.scope - libcontainer container 5ad1dc1977e8569ad7c5c6ef7c5abbe3fabf2a3c39ee41315de60f47e943424f. Sep 8 00:40:20.039402 containerd[1453]: time="2026-09-08T00:40:20.039345788Z" level=info msg="StartContainer for \"5ad1dc1977e8569ad7c5c6ef7c5abbe3fabf2a3c39ee41315de60f47e943424f\" returns successfully" Sep 8 00:40:20.041714 kubelet[1726]: I0908 00:40:20.041665 1726 server.go:177] "Pod update broadcasted" podUID="407e6e93-0837-46c0-a580-598c1d4c9d54" type="MODIFIED" Sep 8 00:40:20.052703 kubelet[1726]: I0908 00:40:20.052549 1726 pod_startup_latency_tracker.go:144] "Observed pod startup duration" pod="default/nginx-deployment-5b96b857d-9w76f" podStartSLOduration=1.267829544 podStartE2EDuration="5.052534919s" totalImagesPullingTime="3.784705375s" totalInitContainerRuntime="0s" isStatefulPod=false podCreationTimestamp="2026-09-08 00:40:15 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-08 00:40:20.052173114 +0000 UTC m=+15.948960885" watchObservedRunningTime="2026-09-08 00:40:20.052534919 +0000 UTC m=+15.949322702" Sep 8 00:40:20.443467 kubelet[1726]: E0908 00:40:20.443368 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:21.444890 kubelet[1726]: E0908 00:40:21.444740 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:21.678363 kubelet[1726]: I0908 00:40:21.678117 1726 server.go:177] "Pod update broadcasted" podUID="5704e03e-6278-481e-80ef-87e853725f3a" type="ADDED" Sep 8 00:40:21.683370 systemd[1]: Created slice kubepods-besteffort-pod5704e03e_6278_481e_80ef_87e853725f3a.slice - libcontainer container kubepods-besteffort-pod5704e03e_6278_481e_80ef_87e853725f3a.slice. Sep 8 00:40:21.684904 kubelet[1726]: I0908 00:40:21.684861 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/5704e03e-6278-481e-80ef-87e853725f3a-data\") pod \"nfs-server-provisioner-0\" (UID: \"5704e03e-6278-481e-80ef-87e853725f3a\") " pod="default/nfs-server-provisioner-0" Sep 8 00:40:21.684904 kubelet[1726]: I0908 00:40:21.684891 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5vzlm\" (UniqueName: \"kubernetes.io/projected/5704e03e-6278-481e-80ef-87e853725f3a-kube-api-access-5vzlm\") pod \"nfs-server-provisioner-0\" (UID: \"5704e03e-6278-481e-80ef-87e853725f3a\") " pod="default/nfs-server-provisioner-0" Sep 8 00:40:21.991244 containerd[1453]: time="2026-09-08T00:40:21.991178487Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:5704e03e-6278-481e-80ef-87e853725f3a,Namespace:default,Attempt:0,}" Sep 8 00:40:22.012205 systemd-networkd[1385]: vethc239fb30: Link UP Sep 8 00:40:22.016351 kernel: cni0: port 2(vethc239fb30) entered blocking state Sep 8 00:40:22.016393 kernel: cni0: port 2(vethc239fb30) entered disabled state Sep 8 00:40:22.016413 kernel: vethc239fb30: entered allmulticast mode Sep 8 00:40:22.016424 kernel: vethc239fb30: entered promiscuous mode Sep 8 00:40:22.024117 kernel: cni0: port 2(vethc239fb30) entered blocking state Sep 8 00:40:22.024173 kernel: cni0: port 2(vethc239fb30) entered forwarding state Sep 8 00:40:22.024185 systemd-networkd[1385]: vethc239fb30: Gained carrier Sep 8 00:40:22.026741 containerd[1453]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a950), "name":"cbr0", "type":"bridge"} Sep 8 00:40:22.026741 containerd[1453]: delegateAdd: netconf sent to delegate plugin: Sep 8 00:40:22.060857 containerd[1453]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2026-09-08T00:40:22.060588426Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 8 00:40:22.061030 containerd[1453]: time="2026-09-08T00:40:22.060787688Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 8 00:40:22.061030 containerd[1453]: time="2026-09-08T00:40:22.060800174Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:22.061030 containerd[1453]: time="2026-09-08T00:40:22.060907902Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:22.080987 systemd[1]: Started cri-containerd-906397ee92a67adba649d386bade00101a1ad67696276ccbb209d81b28c5a589.scope - libcontainer container 906397ee92a67adba649d386bade00101a1ad67696276ccbb209d81b28c5a589. Sep 8 00:40:22.094055 systemd-resolved[1339]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 8 00:40:22.195478 containerd[1453]: time="2026-09-08T00:40:22.195403784Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:5704e03e-6278-481e-80ef-87e853725f3a,Namespace:default,Attempt:0,} returns sandbox id \"906397ee92a67adba649d386bade00101a1ad67696276ccbb209d81b28c5a589\"" Sep 8 00:40:22.196698 kubelet[1726]: I0908 00:40:22.196663 1726 server.go:177] "Pod update broadcasted" podUID="5704e03e-6278-481e-80ef-87e853725f3a" type="MODIFIED" Sep 8 00:40:22.197742 containerd[1453]: time="2026-09-08T00:40:22.197687185Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 8 00:40:22.446222 kubelet[1726]: E0908 00:40:22.445926 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:23.391915 systemd-networkd[1385]: vethc239fb30: Gained IPv6LL Sep 8 00:40:23.670829 kubelet[1726]: E0908 00:40:23.652985 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:24.280533 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4150755906.mount: Deactivated successfully. Sep 8 00:40:24.395546 kubelet[1726]: E0908 00:40:24.395333 1726 file.go:103] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:24.653178 kubelet[1726]: E0908 00:40:24.653073 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:25.656350 kubelet[1726]: E0908 00:40:25.655915 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:26.348944 containerd[1453]: time="2026-09-08T00:40:26.348860789Z" 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 8 00:40:26.349309 containerd[1453]: time="2026-09-08T00:40:26.349233001Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039378" Sep 8 00:40:26.349921 containerd[1453]: time="2026-09-08T00:40:26.349872025Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:26.351981 containerd[1453]: time="2026-09-08T00:40:26.351957196Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:26.353327 containerd[1453]: time="2026-09-08T00:40:26.353295151Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.15557597s" Sep 8 00:40:26.353370 containerd[1453]: time="2026-09-08T00:40:26.353328834Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Sep 8 00:40:26.356020 containerd[1453]: time="2026-09-08T00:40:26.355985034Z" level=info msg="CreateContainer within sandbox \"906397ee92a67adba649d386bade00101a1ad67696276ccbb209d81b28c5a589\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 8 00:40:26.370573 containerd[1453]: time="2026-09-08T00:40:26.370520924Z" level=info msg="CreateContainer within sandbox \"906397ee92a67adba649d386bade00101a1ad67696276ccbb209d81b28c5a589\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"09760091e686e4a987fb3ad165c2334f5c64e85453fa3886e8b556db1da0d5e6\"" Sep 8 00:40:26.371181 containerd[1453]: time="2026-09-08T00:40:26.371150350Z" level=info msg="StartContainer for \"09760091e686e4a987fb3ad165c2334f5c64e85453fa3886e8b556db1da0d5e6\"" Sep 8 00:40:26.419064 systemd[1]: Started cri-containerd-09760091e686e4a987fb3ad165c2334f5c64e85453fa3886e8b556db1da0d5e6.scope - libcontainer container 09760091e686e4a987fb3ad165c2334f5c64e85453fa3886e8b556db1da0d5e6. Sep 8 00:40:26.446332 containerd[1453]: time="2026-09-08T00:40:26.446293386Z" level=info msg="StartContainer for \"09760091e686e4a987fb3ad165c2334f5c64e85453fa3886e8b556db1da0d5e6\" returns successfully" Sep 8 00:40:26.448466 kubelet[1726]: I0908 00:40:26.448435 1726 server.go:177] "Pod update broadcasted" podUID="5704e03e-6278-481e-80ef-87e853725f3a" type="MODIFIED" Sep 8 00:40:26.459014 kubelet[1726]: I0908 00:40:26.458946 1726 pod_startup_latency_tracker.go:144] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.302227233 podStartE2EDuration="5.458934508s" totalImagesPullingTime="4.156707275s" totalInitContainerRuntime="0s" isStatefulPod=false podCreationTimestamp="2026-09-08 00:40:21 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-08 00:40:26.458841263 +0000 UTC m=+22.355629055" watchObservedRunningTime="2026-09-08 00:40:26.458934508 +0000 UTC m=+22.355722279" Sep 8 00:40:26.656836 kubelet[1726]: E0908 00:40:26.656585 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:27.657706 kubelet[1726]: E0908 00:40:27.657605 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:28.658032 kubelet[1726]: E0908 00:40:28.657910 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:29.659450 kubelet[1726]: E0908 00:40:29.659347 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:30.660099 kubelet[1726]: E0908 00:40:30.660021 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:31.660788 kubelet[1726]: E0908 00:40:31.660602 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:31.712570 kubelet[1726]: I0908 00:40:31.712507 1726 server.go:177] "Pod update broadcasted" podUID="2277544a-fe66-4dd1-85ed-5e89972b93a6" type="ADDED" Sep 8 00:40:31.718113 systemd[1]: Created slice kubepods-besteffort-pod2277544a_fe66_4dd1_85ed_5e89972b93a6.slice - libcontainer container kubepods-besteffort-pod2277544a_fe66_4dd1_85ed_5e89972b93a6.slice. Sep 8 00:40:31.890942 kubelet[1726]: I0908 00:40:31.890847 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-443bb04b-e25c-4454-8429-25c310418756\" (UniqueName: \"kubernetes.io/nfs/2277544a-fe66-4dd1-85ed-5e89972b93a6-pvc-443bb04b-e25c-4454-8429-25c310418756\") pod \"test-pod-1\" (UID: \"2277544a-fe66-4dd1-85ed-5e89972b93a6\") " pod="default/test-pod-1" Sep 8 00:40:31.890942 kubelet[1726]: I0908 00:40:31.890937 1726 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-llmvp\" (UniqueName: \"kubernetes.io/projected/2277544a-fe66-4dd1-85ed-5e89972b93a6-kube-api-access-llmvp\") pod \"test-pod-1\" (UID: \"2277544a-fe66-4dd1-85ed-5e89972b93a6\") " pod="default/test-pod-1" Sep 8 00:40:32.021703 kernel: FS-Cache: Loaded Sep 8 00:40:32.122737 kernel: RPC: Registered named UNIX socket transport module. Sep 8 00:40:32.122893 kernel: RPC: Registered udp transport module. Sep 8 00:40:32.122909 kernel: RPC: Registered tcp transport module. Sep 8 00:40:32.124229 kernel: RPC: Registered tcp-with-tls transport module. Sep 8 00:40:32.124252 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 8 00:40:32.342093 kernel: NFS: Registering the id_resolver key type Sep 8 00:40:32.342261 kernel: Key type id_resolver registered Sep 8 00:40:32.342281 kernel: Key type id_legacy registered Sep 8 00:40:32.372837 nfsidmap[2739]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 8 00:40:32.377860 nfsidmap[2742]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 8 00:40:32.625793 containerd[1453]: time="2026-09-08T00:40:32.625539367Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2277544a-fe66-4dd1-85ed-5e89972b93a6,Namespace:default,Attempt:0,}" Sep 8 00:40:32.657753 systemd-networkd[1385]: vethc14b8d00: Link UP Sep 8 00:40:32.659267 kernel: cni0: port 3(vethc14b8d00) entered blocking state Sep 8 00:40:32.659313 kernel: cni0: port 3(vethc14b8d00) entered disabled state Sep 8 00:40:32.659324 kernel: vethc14b8d00: entered allmulticast mode Sep 8 00:40:32.660904 kernel: vethc14b8d00: entered promiscuous mode Sep 8 00:40:32.661025 kubelet[1726]: E0908 00:40:32.660968 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:32.671343 kernel: cni0: port 3(vethc14b8d00) entered blocking state Sep 8 00:40:32.671392 kernel: cni0: port 3(vethc14b8d00) entered forwarding state Sep 8 00:40:32.671107 systemd-networkd[1385]: vethc14b8d00: Gained carrier Sep 8 00:40:32.673608 containerd[1453]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000202230), "name":"cbr0", "type":"bridge"} Sep 8 00:40:32.673608 containerd[1453]: delegateAdd: netconf sent to delegate plugin: Sep 8 00:40:32.697419 containerd[1453]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2026-09-08T00:40:32.696939479Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 8 00:40:32.697419 containerd[1453]: time="2026-09-08T00:40:32.697029918Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 8 00:40:32.697419 containerd[1453]: time="2026-09-08T00:40:32.697078787Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:32.697419 containerd[1453]: time="2026-09-08T00:40:32.697141159Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 8 00:40:32.727032 systemd[1]: Started cri-containerd-e60f8663983045a4284f632205128cc58e9c32384ae4c174b9ffdd63b1b2ae65.scope - libcontainer container e60f8663983045a4284f632205128cc58e9c32384ae4c174b9ffdd63b1b2ae65. Sep 8 00:40:32.776370 systemd-resolved[1339]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 8 00:40:32.805129 containerd[1453]: time="2026-09-08T00:40:32.805047965Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2277544a-fe66-4dd1-85ed-5e89972b93a6,Namespace:default,Attempt:0,} returns sandbox id \"e60f8663983045a4284f632205128cc58e9c32384ae4c174b9ffdd63b1b2ae65\"" Sep 8 00:40:32.806090 kubelet[1726]: I0908 00:40:32.806032 1726 server.go:177] "Pod update broadcasted" podUID="2277544a-fe66-4dd1-85ed-5e89972b93a6" type="MODIFIED" Sep 8 00:40:32.806473 containerd[1453]: time="2026-09-08T00:40:32.806451547Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 8 00:40:33.184725 containerd[1453]: time="2026-09-08T00:40:33.184655770Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 8 00:40:33.185101 containerd[1453]: time="2026-09-08T00:40:33.185055696Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 8 00:40:33.187791 containerd[1453]: time="2026-09-08T00:40:33.187730705Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:3c175c1aa411ed8265ccc495ebd34815c53bb937bc90a54ce24708a48ee5e0b7\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:e68933b249335b5a57ee334d1d621e5e881d280ed29fdcadfbaa34b963a52a01\", size \"67099797\" in 381.245214ms" Sep 8 00:40:33.187851 containerd[1453]: time="2026-09-08T00:40:33.187791764Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:3c175c1aa411ed8265ccc495ebd34815c53bb937bc90a54ce24708a48ee5e0b7\"" Sep 8 00:40:33.190291 containerd[1453]: time="2026-09-08T00:40:33.190262431Z" level=info msg="CreateContainer within sandbox \"e60f8663983045a4284f632205128cc58e9c32384ae4c174b9ffdd63b1b2ae65\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 8 00:40:33.203129 containerd[1453]: time="2026-09-08T00:40:33.203084655Z" level=info msg="CreateContainer within sandbox \"e60f8663983045a4284f632205128cc58e9c32384ae4c174b9ffdd63b1b2ae65\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"0a147d7135afa3d3824a8a786f873c0929901aa6d198494a41216717d223ceba\"" Sep 8 00:40:33.203544 containerd[1453]: time="2026-09-08T00:40:33.203525808Z" level=info msg="StartContainer for \"0a147d7135afa3d3824a8a786f873c0929901aa6d198494a41216717d223ceba\"" Sep 8 00:40:33.243913 systemd[1]: Started cri-containerd-0a147d7135afa3d3824a8a786f873c0929901aa6d198494a41216717d223ceba.scope - libcontainer container 0a147d7135afa3d3824a8a786f873c0929901aa6d198494a41216717d223ceba. Sep 8 00:40:33.267075 containerd[1453]: time="2026-09-08T00:40:33.267038131Z" level=info msg="StartContainer for \"0a147d7135afa3d3824a8a786f873c0929901aa6d198494a41216717d223ceba\" returns successfully" Sep 8 00:40:33.269893 kubelet[1726]: I0908 00:40:33.269855 1726 server.go:177] "Pod update broadcasted" podUID="2277544a-fe66-4dd1-85ed-5e89972b93a6" type="MODIFIED" Sep 8 00:40:33.279152 kubelet[1726]: I0908 00:40:33.279082 1726 pod_startup_latency_tracker.go:144] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=11.896340597 podStartE2EDuration="12.279067123s" totalImagesPullingTime="382.726526ms" totalInitContainerRuntime="0s" isStatefulPod=true podCreationTimestamp="2026-09-08 00:40:21 +0000 UTC" imagePullSessionsCount=1 imagePullSessionsStartsCount=0 observedRunningTime="2026-09-08 00:40:33.278970394 +0000 UTC m=+29.175758165" watchObservedRunningTime="2026-09-08 00:40:33.279067123 +0000 UTC m=+29.175854904" Sep 8 00:40:33.662186 kubelet[1726]: E0908 00:40:33.662091 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:33.760117 systemd-networkd[1385]: vethc14b8d00: Gained IPv6LL Sep 8 00:40:34.663143 kubelet[1726]: E0908 00:40:34.663058 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:35.663487 kubelet[1726]: E0908 00:40:35.663387 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:36.664705 kubelet[1726]: E0908 00:40:36.664582 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:37.665666 kubelet[1726]: E0908 00:40:37.665559 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 8 00:40:38.666342 kubelet[1726]: E0908 00:40:38.666232 1726 file_linux.go:59] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"