May 16 00:12:51.975927 kernel: Linux version 6.6.90-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Thu May 15 22:19:35 -00 2025 May 16 00:12:51.975950 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=3eac1ac065bd62ee8513964addbc130593421d288f32dda9b1fb7c667f95e96b May 16 00:12:51.975961 kernel: BIOS-provided physical RAM map: May 16 00:12:51.975967 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 16 00:12:51.975973 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 16 00:12:51.975979 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 16 00:12:51.975986 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable May 16 00:12:51.975993 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved May 16 00:12:51.975999 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved May 16 00:12:51.976008 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved May 16 00:12:51.976014 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 16 00:12:51.976020 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 16 00:12:51.976026 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 16 00:12:51.976033 kernel: NX (Execute Disable) protection: active May 16 00:12:51.976040 kernel: APIC: Static calls initialized May 16 00:12:51.976049 kernel: SMBIOS 2.8 present. May 16 00:12:51.976056 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 May 16 00:12:51.976063 kernel: Hypervisor detected: KVM May 16 00:12:51.976070 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 16 00:12:51.976076 kernel: kvm-clock: using sched offset of 2380941955 cycles May 16 00:12:51.976083 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 16 00:12:51.976090 kernel: tsc: Detected 2794.748 MHz processor May 16 00:12:51.976098 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 16 00:12:51.976105 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 16 00:12:51.976112 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 May 16 00:12:51.976122 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs May 16 00:12:51.976129 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 16 00:12:51.976135 kernel: Using GB pages for direct mapping May 16 00:12:51.976142 kernel: ACPI: Early table checksum verification disabled May 16 00:12:51.976149 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) May 16 00:12:51.976156 kernel: ACPI: RSDT 0x000000009CFE2408 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:12:51.976163 kernel: ACPI: FACP 0x000000009CFE21E8 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:12:51.976170 kernel: ACPI: DSDT 0x000000009CFE0040 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:12:51.976179 kernel: ACPI: FACS 0x000000009CFE0000 000040 May 16 00:12:51.976186 kernel: ACPI: APIC 0x000000009CFE22DC 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:12:51.976193 kernel: ACPI: HPET 0x000000009CFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:12:51.976200 kernel: ACPI: MCFG 0x000000009CFE23A4 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:12:51.976207 kernel: ACPI: WAET 0x000000009CFE23E0 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 16 00:12:51.976223 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21e8-0x9cfe22db] May 16 00:12:51.976230 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21e7] May 16 00:12:51.976241 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] May 16 00:12:51.976250 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22dc-0x9cfe236b] May 16 00:12:51.976257 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe236c-0x9cfe23a3] May 16 00:12:51.976264 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23a4-0x9cfe23df] May 16 00:12:51.976272 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23e0-0x9cfe2407] May 16 00:12:51.976279 kernel: No NUMA configuration found May 16 00:12:51.976286 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] May 16 00:12:51.976296 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] May 16 00:12:51.976303 kernel: Zone ranges: May 16 00:12:51.976310 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 16 00:12:51.976317 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] May 16 00:12:51.976324 kernel: Normal empty May 16 00:12:51.976331 kernel: Movable zone start for each node May 16 00:12:51.976338 kernel: Early memory node ranges May 16 00:12:51.976347 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 16 00:12:51.976355 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] May 16 00:12:51.976362 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] May 16 00:12:51.976372 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 16 00:12:51.976379 kernel: On node 0, zone DMA: 97 pages in unavailable ranges May 16 00:12:51.976386 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges May 16 00:12:51.976393 kernel: ACPI: PM-Timer IO Port: 0x608 May 16 00:12:51.976400 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 16 00:12:51.976407 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 16 00:12:51.976414 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 16 00:12:51.976422 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 16 00:12:51.976429 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 16 00:12:51.976438 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 16 00:12:51.976445 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 16 00:12:51.976453 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 16 00:12:51.976460 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 16 00:12:51.976467 kernel: TSC deadline timer available May 16 00:12:51.976474 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 16 00:12:51.976481 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 16 00:12:51.976488 kernel: kvm-guest: KVM setup pv remote TLB flush May 16 00:12:51.976495 kernel: kvm-guest: setup PV sched yield May 16 00:12:51.976505 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices May 16 00:12:51.976512 kernel: Booting paravirtualized kernel on KVM May 16 00:12:51.976519 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 16 00:12:51.976545 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 16 00:12:51.976552 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 May 16 00:12:51.976560 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 May 16 00:12:51.976567 kernel: pcpu-alloc: [0] 0 1 2 3 May 16 00:12:51.976574 kernel: kvm-guest: PV spinlocks enabled May 16 00:12:51.976583 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 16 00:12:51.976595 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=3eac1ac065bd62ee8513964addbc130593421d288f32dda9b1fb7c667f95e96b May 16 00:12:51.976603 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 16 00:12:51.976610 kernel: random: crng init done May 16 00:12:51.976617 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 16 00:12:51.976624 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 16 00:12:51.976631 kernel: Fallback order for Node 0: 0 May 16 00:12:51.976639 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 May 16 00:12:51.976646 kernel: Policy zone: DMA32 May 16 00:12:51.976653 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 16 00:12:51.976663 kernel: Memory: 2434592K/2571752K available (12288K kernel code, 2295K rwdata, 22752K rodata, 42988K init, 2204K bss, 136900K reserved, 0K cma-reserved) May 16 00:12:51.976670 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 16 00:12:51.976677 kernel: ftrace: allocating 37950 entries in 149 pages May 16 00:12:51.976684 kernel: ftrace: allocated 149 pages with 4 groups May 16 00:12:51.976693 kernel: Dynamic Preempt: voluntary May 16 00:12:51.976702 kernel: rcu: Preemptible hierarchical RCU implementation. May 16 00:12:51.976711 kernel: rcu: RCU event tracing is enabled. May 16 00:12:51.976718 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 16 00:12:51.976725 kernel: Trampoline variant of Tasks RCU enabled. May 16 00:12:51.976736 kernel: Rude variant of Tasks RCU enabled. May 16 00:12:51.976743 kernel: Tracing variant of Tasks RCU enabled. May 16 00:12:51.976750 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 16 00:12:51.976758 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 16 00:12:51.976767 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 May 16 00:12:51.976775 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 16 00:12:51.976783 kernel: Console: colour VGA+ 80x25 May 16 00:12:51.976792 kernel: printk: console [ttyS0] enabled May 16 00:12:51.976799 kernel: ACPI: Core revision 20230628 May 16 00:12:51.976809 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 16 00:12:51.976816 kernel: APIC: Switch to symmetric I/O mode setup May 16 00:12:51.976823 kernel: x2apic enabled May 16 00:12:51.976830 kernel: APIC: Switched APIC routing to: physical x2apic May 16 00:12:51.976838 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() May 16 00:12:51.976845 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() May 16 00:12:51.976852 kernel: kvm-guest: setup PV IPIs May 16 00:12:51.976869 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 16 00:12:51.976877 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized May 16 00:12:51.976887 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) May 16 00:12:51.976897 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 16 00:12:51.976910 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 May 16 00:12:51.976920 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 May 16 00:12:51.976930 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 16 00:12:51.976940 kernel: Spectre V2 : Mitigation: Retpolines May 16 00:12:51.976951 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 16 00:12:51.976964 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls May 16 00:12:51.976974 kernel: RETBleed: Mitigation: untrained return thunk May 16 00:12:51.976982 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 16 00:12:51.976990 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 16 00:12:51.976998 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! May 16 00:12:51.977006 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. May 16 00:12:51.977014 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode May 16 00:12:51.977022 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 16 00:12:51.977032 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 16 00:12:51.977040 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 16 00:12:51.977047 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 16 00:12:51.977055 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. May 16 00:12:51.977063 kernel: Freeing SMP alternatives memory: 32K May 16 00:12:51.977070 kernel: pid_max: default: 32768 minimum: 301 May 16 00:12:51.977077 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 16 00:12:51.977085 kernel: landlock: Up and running. May 16 00:12:51.977093 kernel: SELinux: Initializing. May 16 00:12:51.977106 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 16 00:12:51.977117 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 16 00:12:51.977127 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) May 16 00:12:51.977134 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 16 00:12:51.977144 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 16 00:12:51.977155 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 16 00:12:51.977164 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. May 16 00:12:51.977172 kernel: ... version: 0 May 16 00:12:51.977179 kernel: ... bit width: 48 May 16 00:12:51.977190 kernel: ... generic registers: 6 May 16 00:12:51.977197 kernel: ... value mask: 0000ffffffffffff May 16 00:12:51.977205 kernel: ... max period: 00007fffffffffff May 16 00:12:51.977221 kernel: ... fixed-purpose events: 0 May 16 00:12:51.977229 kernel: ... event mask: 000000000000003f May 16 00:12:51.977237 kernel: signal: max sigframe size: 1776 May 16 00:12:51.977245 kernel: rcu: Hierarchical SRCU implementation. May 16 00:12:51.977253 kernel: rcu: Max phase no-delay instances is 400. May 16 00:12:51.977260 kernel: smp: Bringing up secondary CPUs ... May 16 00:12:51.977270 kernel: smpboot: x86: Booting SMP configuration: May 16 00:12:51.977278 kernel: .... node #0, CPUs: #1 #2 #3 May 16 00:12:51.977286 kernel: smp: Brought up 1 node, 4 CPUs May 16 00:12:51.977296 kernel: smpboot: Max logical packages: 1 May 16 00:12:51.977307 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) May 16 00:12:51.977317 kernel: devtmpfs: initialized May 16 00:12:51.977325 kernel: x86/mm: Memory block size: 128MB May 16 00:12:51.977336 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 16 00:12:51.977346 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 16 00:12:51.977355 kernel: pinctrl core: initialized pinctrl subsystem May 16 00:12:51.977366 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 16 00:12:51.977373 kernel: audit: initializing netlink subsys (disabled) May 16 00:12:51.977381 kernel: audit: type=2000 audit(1747354371.364:1): state=initialized audit_enabled=0 res=1 May 16 00:12:51.977389 kernel: thermal_sys: Registered thermal governor 'step_wise' May 16 00:12:51.977396 kernel: thermal_sys: Registered thermal governor 'user_space' May 16 00:12:51.977404 kernel: cpuidle: using governor menu May 16 00:12:51.977412 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 16 00:12:51.977420 kernel: dca service started, version 1.12.1 May 16 00:12:51.977427 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) May 16 00:12:51.977437 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry May 16 00:12:51.977445 kernel: PCI: Using configuration type 1 for base access May 16 00:12:51.977453 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 16 00:12:51.977461 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 16 00:12:51.977469 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 16 00:12:51.977476 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 16 00:12:51.977484 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 16 00:12:51.977492 kernel: ACPI: Added _OSI(Module Device) May 16 00:12:51.977501 kernel: ACPI: Added _OSI(Processor Device) May 16 00:12:51.977509 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 16 00:12:51.977517 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 16 00:12:51.977666 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 16 00:12:51.977674 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 16 00:12:51.977682 kernel: ACPI: Interpreter enabled May 16 00:12:51.977689 kernel: ACPI: PM: (supports S0 S3 S5) May 16 00:12:51.977697 kernel: ACPI: Using IOAPIC for interrupt routing May 16 00:12:51.977705 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 16 00:12:51.977712 kernel: PCI: Using E820 reservations for host bridge windows May 16 00:12:51.977722 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F May 16 00:12:51.977730 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 16 00:12:51.977914 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 16 00:12:51.978045 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] May 16 00:12:51.978166 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] May 16 00:12:51.978176 kernel: PCI host bridge to bus 0000:00 May 16 00:12:51.978310 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 16 00:12:51.978425 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 16 00:12:51.978558 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 16 00:12:51.978686 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] May 16 00:12:51.978817 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] May 16 00:12:51.978980 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] May 16 00:12:51.979115 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 16 00:12:51.979294 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 May 16 00:12:51.979449 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 May 16 00:12:51.979609 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] May 16 00:12:51.979764 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] May 16 00:12:51.979923 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] May 16 00:12:51.980068 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 16 00:12:51.980238 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 May 16 00:12:51.980375 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] May 16 00:12:51.980512 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] May 16 00:12:51.980669 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] May 16 00:12:51.980820 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 May 16 00:12:51.980964 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] May 16 00:12:51.981109 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] May 16 00:12:51.981256 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] May 16 00:12:51.981412 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 16 00:12:51.981566 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] May 16 00:12:51.981713 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] May 16 00:12:51.981854 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] May 16 00:12:51.981981 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] May 16 00:12:51.982114 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 May 16 00:12:51.982249 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO May 16 00:12:51.982383 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 May 16 00:12:51.982506 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] May 16 00:12:51.982645 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] May 16 00:12:51.982773 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 May 16 00:12:51.982898 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] May 16 00:12:51.982909 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 16 00:12:51.982921 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 16 00:12:51.982929 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 16 00:12:51.982940 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 16 00:12:51.982948 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 May 16 00:12:51.982957 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 May 16 00:12:51.982968 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 May 16 00:12:51.982979 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 May 16 00:12:51.982990 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 May 16 00:12:51.983000 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 May 16 00:12:51.983013 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 May 16 00:12:51.983023 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 May 16 00:12:51.983031 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 May 16 00:12:51.983039 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 May 16 00:12:51.983046 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 May 16 00:12:51.983054 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 May 16 00:12:51.983063 kernel: iommu: Default domain type: Translated May 16 00:12:51.983072 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 16 00:12:51.983080 kernel: PCI: Using ACPI for IRQ routing May 16 00:12:51.983090 kernel: PCI: pci_cache_line_size set to 64 bytes May 16 00:12:51.983098 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 16 00:12:51.983105 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] May 16 00:12:51.983259 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device May 16 00:12:51.983401 kernel: pci 0000:00:01.0: vgaarb: bridge control possible May 16 00:12:51.983551 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 16 00:12:51.983566 kernel: vgaarb: loaded May 16 00:12:51.983576 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 16 00:12:51.983589 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 16 00:12:51.983597 kernel: clocksource: Switched to clocksource kvm-clock May 16 00:12:51.983604 kernel: VFS: Disk quotas dquot_6.6.0 May 16 00:12:51.983613 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 16 00:12:51.983620 kernel: pnp: PnP ACPI init May 16 00:12:51.983769 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved May 16 00:12:51.983784 kernel: pnp: PnP ACPI: found 6 devices May 16 00:12:51.983793 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 16 00:12:51.983800 kernel: NET: Registered PF_INET protocol family May 16 00:12:51.983812 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 16 00:12:51.983819 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 16 00:12:51.983828 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 16 00:12:51.983838 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 16 00:12:51.983846 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 16 00:12:51.983854 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 16 00:12:51.983861 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 16 00:12:51.983869 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 16 00:12:51.983879 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 16 00:12:51.983887 kernel: NET: Registered PF_XDP protocol family May 16 00:12:51.984016 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 16 00:12:51.984128 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 16 00:12:51.984250 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 16 00:12:51.984362 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] May 16 00:12:51.984472 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] May 16 00:12:51.984597 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] May 16 00:12:51.984608 kernel: PCI: CLS 0 bytes, default 64 May 16 00:12:51.984620 kernel: Initialise system trusted keyrings May 16 00:12:51.984627 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 16 00:12:51.984635 kernel: Key type asymmetric registered May 16 00:12:51.984642 kernel: Asymmetric key parser 'x509' registered May 16 00:12:51.984650 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 16 00:12:51.984658 kernel: io scheduler mq-deadline registered May 16 00:12:51.984665 kernel: io scheduler kyber registered May 16 00:12:51.984673 kernel: io scheduler bfq registered May 16 00:12:51.984681 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 16 00:12:51.984692 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 May 16 00:12:51.984700 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 May 16 00:12:51.984707 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 May 16 00:12:51.984716 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 16 00:12:51.984725 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 16 00:12:51.984735 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 16 00:12:51.984746 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 16 00:12:51.984760 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 16 00:12:51.984924 kernel: rtc_cmos 00:04: RTC can wake from S4 May 16 00:12:51.984945 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 16 00:12:51.985078 kernel: rtc_cmos 00:04: registered as rtc0 May 16 00:12:51.985222 kernel: rtc_cmos 00:04: setting system clock to 2025-05-16T00:12:51 UTC (1747354371) May 16 00:12:51.985359 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs May 16 00:12:51.985373 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled May 16 00:12:51.985384 kernel: NET: Registered PF_INET6 protocol family May 16 00:12:51.985394 kernel: Segment Routing with IPv6 May 16 00:12:51.985403 kernel: In-situ OAM (IOAM) with IPv6 May 16 00:12:51.985417 kernel: NET: Registered PF_PACKET protocol family May 16 00:12:51.985424 kernel: Key type dns_resolver registered May 16 00:12:51.985432 kernel: IPI shorthand broadcast: enabled May 16 00:12:51.985440 kernel: sched_clock: Marking stable (617003950, 169622468)->(927183932, -140557514) May 16 00:12:51.985447 kernel: registered taskstats version 1 May 16 00:12:51.985456 kernel: Loading compiled-in X.509 certificates May 16 00:12:51.985467 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.90-flatcar: 563478d245b598189519397611f5bddee97f3fc1' May 16 00:12:51.985478 kernel: Key type .fscrypt registered May 16 00:12:51.985489 kernel: Key type fscrypt-provisioning registered May 16 00:12:51.985502 kernel: ima: No TPM chip found, activating TPM-bypass! May 16 00:12:51.985512 kernel: ima: Allocated hash algorithm: sha1 May 16 00:12:51.985614 kernel: ima: No architecture policies found May 16 00:12:51.985626 kernel: clk: Disabling unused clocks May 16 00:12:51.985636 kernel: Freeing unused kernel image (initmem) memory: 42988K May 16 00:12:51.985646 kernel: Write protecting the kernel read-only data: 36864k May 16 00:12:51.985656 kernel: Freeing unused kernel image (rodata/data gap) memory: 1824K May 16 00:12:51.985665 kernel: Run /init as init process May 16 00:12:51.985674 kernel: with arguments: May 16 00:12:51.985685 kernel: /init May 16 00:12:51.985693 kernel: with environment: May 16 00:12:51.985701 kernel: HOME=/ May 16 00:12:51.985708 kernel: TERM=linux May 16 00:12:51.985717 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 16 00:12:51.985731 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) May 16 00:12:51.985744 systemd[1]: Detected virtualization kvm. May 16 00:12:51.985760 systemd[1]: Detected architecture x86-64. May 16 00:12:51.985770 systemd[1]: Running in initrd. May 16 00:12:51.985781 systemd[1]: No hostname configured, using default hostname. May 16 00:12:51.985790 systemd[1]: Hostname set to . May 16 00:12:51.985801 systemd[1]: Initializing machine ID from VM UUID. May 16 00:12:51.985810 systemd[1]: Queued start job for default target initrd.target. May 16 00:12:51.985818 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 16 00:12:51.985826 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 16 00:12:51.985838 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 16 00:12:51.985847 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 16 00:12:51.985874 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 16 00:12:51.985889 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 16 00:12:51.985902 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 16 00:12:51.985916 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 16 00:12:51.985925 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 16 00:12:51.985937 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 16 00:12:51.985945 systemd[1]: Reached target paths.target - Path Units. May 16 00:12:51.985953 systemd[1]: Reached target slices.target - Slice Units. May 16 00:12:51.985962 systemd[1]: Reached target swap.target - Swaps. May 16 00:12:51.985970 systemd[1]: Reached target timers.target - Timer Units. May 16 00:12:51.985978 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 16 00:12:51.985988 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 16 00:12:51.986003 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 16 00:12:51.986015 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 16 00:12:51.986027 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 16 00:12:51.986038 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 16 00:12:51.986049 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 16 00:12:51.986059 systemd[1]: Reached target sockets.target - Socket Units. May 16 00:12:51.986070 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 16 00:12:51.986079 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 16 00:12:51.986090 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 16 00:12:51.986099 systemd[1]: Starting systemd-fsck-usr.service... May 16 00:12:51.986107 systemd[1]: Starting systemd-journald.service - Journal Service... May 16 00:12:51.986116 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 16 00:12:51.986128 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 16 00:12:51.986164 systemd-journald[194]: Collecting audit messages is disabled. May 16 00:12:51.986194 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 16 00:12:51.986206 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 16 00:12:51.986223 systemd[1]: Finished systemd-fsck-usr.service. May 16 00:12:51.986232 systemd-journald[194]: Journal started May 16 00:12:51.986256 systemd-journald[194]: Runtime Journal (/run/log/journal/761a21b505cd4e0fba26d84c08b17300) is 6.0M, max 48.4M, 42.3M free. May 16 00:12:51.988581 systemd-modules-load[195]: Inserted module 'overlay' May 16 00:12:52.041944 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 16 00:12:52.041970 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 16 00:12:52.041983 kernel: Bridge firewalling registered May 16 00:12:52.020290 systemd-modules-load[195]: Inserted module 'br_netfilter' May 16 00:12:52.052554 systemd[1]: Started systemd-journald.service - Journal Service. May 16 00:12:52.053295 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 16 00:12:52.054031 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 16 00:12:52.057484 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 16 00:12:52.084663 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 16 00:12:52.085578 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 16 00:12:52.086364 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 16 00:12:52.108060 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 16 00:12:52.120180 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 16 00:12:52.123330 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 16 00:12:52.126320 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 16 00:12:52.129281 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 16 00:12:52.143794 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 16 00:12:52.147574 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 16 00:12:52.159282 dracut-cmdline[230]: dracut-dracut-053 May 16 00:12:52.172402 dracut-cmdline[230]: 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=3eac1ac065bd62ee8513964addbc130593421d288f32dda9b1fb7c667f95e96b May 16 00:12:52.181606 systemd-resolved[231]: Positive Trust Anchors: May 16 00:12:52.181621 systemd-resolved[231]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 16 00:12:52.181658 systemd-resolved[231]: 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 May 16 00:12:52.184180 systemd-resolved[231]: Defaulting to hostname 'linux'. May 16 00:12:52.185273 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 16 00:12:52.201315 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 16 00:12:52.317588 kernel: SCSI subsystem initialized May 16 00:12:52.327556 kernel: Loading iSCSI transport class v2.0-870. May 16 00:12:52.372563 kernel: iscsi: registered transport (tcp) May 16 00:12:52.401879 kernel: iscsi: registered transport (qla4xxx) May 16 00:12:52.401970 kernel: QLogic iSCSI HBA Driver May 16 00:12:52.451601 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 16 00:12:52.459906 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 16 00:12:52.507194 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 16 00:12:52.507273 kernel: device-mapper: uevent: version 1.0.3 May 16 00:12:52.507285 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 16 00:12:52.550565 kernel: raid6: avx2x4 gen() 27815 MB/s May 16 00:12:52.575563 kernel: raid6: avx2x2 gen() 29939 MB/s May 16 00:12:52.607135 kernel: raid6: avx2x1 gen() 24909 MB/s May 16 00:12:52.607188 kernel: raid6: using algorithm avx2x2 gen() 29939 MB/s May 16 00:12:52.624701 kernel: raid6: .... xor() 18493 MB/s, rmw enabled May 16 00:12:52.624799 kernel: raid6: using avx2x2 recovery algorithm May 16 00:12:52.645549 kernel: xor: automatically using best checksumming function avx May 16 00:12:52.807561 kernel: Btrfs loaded, zoned=no, fsverity=no May 16 00:12:52.819602 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 16 00:12:52.850835 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 16 00:12:52.863183 systemd-udevd[414]: Using default interface naming scheme 'v255'. May 16 00:12:52.876854 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 16 00:12:52.880220 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 16 00:12:52.898363 dracut-pre-trigger[426]: rd.md=0: removing MD RAID activation May 16 00:12:52.935171 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 16 00:12:52.952783 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 16 00:12:53.022196 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 16 00:12:53.044665 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 16 00:12:53.056819 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 16 00:12:53.064212 kernel: cryptd: max_cpu_qlen set to 1000 May 16 00:12:53.060591 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 16 00:12:53.091846 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues May 16 00:12:53.093445 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 16 00:12:53.063389 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 16 00:12:53.123196 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 16 00:12:53.123220 kernel: GPT:9289727 != 19775487 May 16 00:12:53.123234 kernel: GPT:Alternate GPT header not at the end of the disk. May 16 00:12:53.123247 kernel: GPT:9289727 != 19775487 May 16 00:12:53.123258 kernel: GPT: Use GNU Parted to correct GPT errors. May 16 00:12:53.123280 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 16 00:12:53.091865 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 16 00:12:53.123704 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 16 00:12:53.139323 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 16 00:12:53.156427 kernel: AVX2 version of gcm_enc/dec engaged. May 16 00:12:53.158386 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 16 00:12:53.163203 kernel: AES CTR mode by8 optimization enabled May 16 00:12:53.158504 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 16 00:12:53.161501 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 16 00:12:53.166649 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 16 00:12:53.166876 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 16 00:12:53.191090 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 16 00:12:53.193759 kernel: libata version 3.00 loaded. May 16 00:12:53.198565 kernel: ahci 0000:00:1f.2: version 3.0 May 16 00:12:53.198764 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 May 16 00:12:53.228929 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode May 16 00:12:53.229207 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only May 16 00:12:53.229398 kernel: BTRFS: device fsid da1480a3-a7d8-4e12-bbe1-1257540eb9ae devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (471) May 16 00:12:53.228894 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 16 00:12:53.235568 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (472) May 16 00:12:53.237615 kernel: scsi host0: ahci May 16 00:12:53.239562 kernel: scsi host1: ahci May 16 00:12:53.241602 kernel: scsi host2: ahci May 16 00:12:53.241849 kernel: scsi host3: ahci May 16 00:12:53.242802 kernel: scsi host4: ahci May 16 00:12:53.243842 kernel: scsi host5: ahci May 16 00:12:53.245064 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 May 16 00:12:53.245089 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 May 16 00:12:53.246440 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 16 00:12:53.262883 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 May 16 00:12:53.262922 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 May 16 00:12:53.262937 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 May 16 00:12:53.262950 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 May 16 00:12:53.280452 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 16 00:12:53.303153 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 16 00:12:53.310347 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 16 00:12:53.325610 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 16 00:12:53.326978 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 16 00:12:53.340817 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 16 00:12:53.357659 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 16 00:12:53.377300 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 16 00:12:53.588573 kernel: ata4: SATA link down (SStatus 0 SControl 300) May 16 00:12:53.588656 kernel: ata6: SATA link down (SStatus 0 SControl 300) May 16 00:12:53.589564 kernel: ata2: SATA link down (SStatus 0 SControl 300) May 16 00:12:53.590564 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 16 00:12:53.591558 kernel: ata1: SATA link down (SStatus 0 SControl 300) May 16 00:12:53.592579 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 May 16 00:12:53.592663 kernel: ata3.00: applying bridge limits May 16 00:12:53.593560 kernel: ata5: SATA link down (SStatus 0 SControl 300) May 16 00:12:53.594573 kernel: ata3.00: configured for UDMA/100 May 16 00:12:53.595562 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 May 16 00:12:53.599681 disk-uuid[567]: Primary Header is updated. May 16 00:12:53.599681 disk-uuid[567]: Secondary Entries is updated. May 16 00:12:53.599681 disk-uuid[567]: Secondary Header is updated. May 16 00:12:53.603552 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 16 00:12:53.654137 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray May 16 00:12:53.654490 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 16 00:12:53.669574 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 May 16 00:12:54.618594 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 16 00:12:54.622205 disk-uuid[578]: The operation has completed successfully. May 16 00:12:54.686287 systemd[1]: disk-uuid.service: Deactivated successfully. May 16 00:12:54.691436 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 16 00:12:54.737186 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 16 00:12:54.773997 sh[594]: Success May 16 00:12:54.815690 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" May 16 00:12:54.909831 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 16 00:12:54.930935 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 16 00:12:54.941653 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 16 00:12:55.000780 kernel: BTRFS info (device dm-0): first mount of filesystem da1480a3-a7d8-4e12-bbe1-1257540eb9ae May 16 00:12:55.000867 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 16 00:12:55.000887 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 16 00:12:55.000904 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 16 00:12:55.000920 kernel: BTRFS info (device dm-0): using free space tree May 16 00:12:55.042464 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 16 00:12:55.055190 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 16 00:12:55.071749 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 16 00:12:55.083492 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 16 00:12:55.103709 kernel: BTRFS info (device vda6): first mount of filesystem 3387f2c6-46d4-43a5-af69-bf48427d85c5 May 16 00:12:55.103760 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 16 00:12:55.103777 kernel: BTRFS info (device vda6): using free space tree May 16 00:12:55.125011 kernel: BTRFS info (device vda6): auto enabling async discard May 16 00:12:55.144589 systemd[1]: mnt-oem.mount: Deactivated successfully. May 16 00:12:55.170762 kernel: BTRFS info (device vda6): last unmount of filesystem 3387f2c6-46d4-43a5-af69-bf48427d85c5 May 16 00:12:55.295034 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 16 00:12:55.338159 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 16 00:12:55.379768 systemd-networkd[772]: lo: Link UP May 16 00:12:55.379782 systemd-networkd[772]: lo: Gained carrier May 16 00:12:55.381613 systemd-networkd[772]: Enumeration completed May 16 00:12:55.382103 systemd-networkd[772]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 16 00:12:55.382109 systemd-networkd[772]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 16 00:12:55.382405 systemd[1]: Started systemd-networkd.service - Network Configuration. May 16 00:12:55.396441 systemd-networkd[772]: eth0: Link UP May 16 00:12:55.396447 systemd-networkd[772]: eth0: Gained carrier May 16 00:12:55.396461 systemd-networkd[772]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 16 00:12:55.398182 systemd[1]: Reached target network.target - Network. May 16 00:12:55.429232 systemd-networkd[772]: eth0: DHCPv4 address 10.0.0.12/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 16 00:12:55.429298 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 16 00:12:55.476967 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 16 00:12:55.623475 ignition[776]: Ignition 2.20.0 May 16 00:12:55.623493 ignition[776]: Stage: fetch-offline May 16 00:12:55.623606 ignition[776]: no configs at "/usr/lib/ignition/base.d" May 16 00:12:55.623624 ignition[776]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:12:55.623767 ignition[776]: parsed url from cmdline: "" May 16 00:12:55.623773 ignition[776]: no config URL provided May 16 00:12:55.623780 ignition[776]: reading system config file "/usr/lib/ignition/user.ign" May 16 00:12:55.623793 ignition[776]: no config at "/usr/lib/ignition/user.ign" May 16 00:12:55.623837 ignition[776]: op(1): [started] loading QEMU firmware config module May 16 00:12:55.623845 ignition[776]: op(1): executing: "modprobe" "qemu_fw_cfg" May 16 00:12:55.660556 ignition[776]: op(1): [finished] loading QEMU firmware config module May 16 00:12:55.662557 ignition[776]: parsing config with SHA512: acd5af0ccf978922231318ec280192f3db222f8d3013eef6cb2a7929d07fdd3639234da9149409d8cafce6f43ff4b281ac3476608eb9193d81dd092b27d60358 May 16 00:12:55.673079 unknown[776]: fetched base config from "system" May 16 00:12:55.673104 unknown[776]: fetched user config from "qemu" May 16 00:12:55.682727 ignition[776]: fetch-offline: fetch-offline passed May 16 00:12:55.684057 ignition[776]: Ignition finished successfully May 16 00:12:55.687173 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 16 00:12:55.690979 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 16 00:12:55.715052 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 16 00:12:55.745715 ignition[786]: Ignition 2.20.0 May 16 00:12:55.748170 ignition[786]: Stage: kargs May 16 00:12:55.752179 ignition[786]: no configs at "/usr/lib/ignition/base.d" May 16 00:12:55.752197 ignition[786]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:12:55.753381 ignition[786]: kargs: kargs passed May 16 00:12:55.753445 ignition[786]: Ignition finished successfully May 16 00:12:55.767745 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 16 00:12:55.783423 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 16 00:12:55.811206 ignition[796]: Ignition 2.20.0 May 16 00:12:55.811221 ignition[796]: Stage: disks May 16 00:12:55.811451 ignition[796]: no configs at "/usr/lib/ignition/base.d" May 16 00:12:55.811467 ignition[796]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:12:55.818005 ignition[796]: disks: disks passed May 16 00:12:55.819870 ignition[796]: Ignition finished successfully May 16 00:12:55.822457 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 16 00:12:55.829092 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 16 00:12:55.830669 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 16 00:12:55.831217 systemd[1]: Reached target local-fs.target - Local File Systems. May 16 00:12:55.831818 systemd[1]: Reached target sysinit.target - System Initialization. May 16 00:12:55.832217 systemd[1]: Reached target basic.target - Basic System. May 16 00:12:55.852940 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 16 00:12:55.877548 systemd-fsck[806]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 16 00:12:55.898649 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 16 00:12:55.914817 systemd[1]: Mounting sysroot.mount - /sysroot... May 16 00:12:56.123591 kernel: EXT4-fs (vda9): mounted filesystem 13a141f5-2ff0-46d9-bee3-974c86536128 r/w with ordered data mode. Quota mode: none. May 16 00:12:56.124067 systemd[1]: Mounted sysroot.mount - /sysroot. May 16 00:12:56.127369 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 16 00:12:56.146124 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 16 00:12:56.158601 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 16 00:12:56.164641 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 16 00:12:56.164728 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 16 00:12:56.195477 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (814) May 16 00:12:56.198248 kernel: BTRFS info (device vda6): first mount of filesystem 3387f2c6-46d4-43a5-af69-bf48427d85c5 May 16 00:12:56.164775 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 16 00:12:56.204254 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 16 00:12:56.204283 kernel: BTRFS info (device vda6): using free space tree May 16 00:12:56.230583 kernel: BTRFS info (device vda6): auto enabling async discard May 16 00:12:56.233822 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 16 00:12:56.245342 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 16 00:12:56.277312 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 16 00:12:56.427172 initrd-setup-root[838]: cut: /sysroot/etc/passwd: No such file or directory May 16 00:12:56.472500 initrd-setup-root[845]: cut: /sysroot/etc/group: No such file or directory May 16 00:12:56.518968 initrd-setup-root[852]: cut: /sysroot/etc/shadow: No such file or directory May 16 00:12:56.546231 initrd-setup-root[859]: cut: /sysroot/etc/gshadow: No such file or directory May 16 00:12:56.807861 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 16 00:12:56.825750 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 16 00:12:56.842346 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 16 00:12:56.849142 kernel: BTRFS info (device vda6): last unmount of filesystem 3387f2c6-46d4-43a5-af69-bf48427d85c5 May 16 00:12:56.849197 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 16 00:12:56.932181 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 16 00:12:56.960823 ignition[929]: INFO : Ignition 2.20.0 May 16 00:12:56.960823 ignition[929]: INFO : Stage: mount May 16 00:12:56.966404 ignition[929]: INFO : no configs at "/usr/lib/ignition/base.d" May 16 00:12:56.966404 ignition[929]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:12:56.966404 ignition[929]: INFO : mount: mount passed May 16 00:12:56.966404 ignition[929]: INFO : Ignition finished successfully May 16 00:12:56.973204 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 16 00:12:56.992989 systemd[1]: Starting ignition-files.service - Ignition (files)... May 16 00:12:57.144711 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 16 00:12:57.167580 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (941) May 16 00:12:57.174191 kernel: BTRFS info (device vda6): first mount of filesystem 3387f2c6-46d4-43a5-af69-bf48427d85c5 May 16 00:12:57.174260 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 16 00:12:57.174276 kernel: BTRFS info (device vda6): using free space tree May 16 00:12:57.177182 systemd-networkd[772]: eth0: Gained IPv6LL May 16 00:12:57.195582 kernel: BTRFS info (device vda6): auto enabling async discard May 16 00:12:57.202643 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 16 00:12:57.257273 ignition[958]: INFO : Ignition 2.20.0 May 16 00:12:57.257273 ignition[958]: INFO : Stage: files May 16 00:12:57.261148 ignition[958]: INFO : no configs at "/usr/lib/ignition/base.d" May 16 00:12:57.261148 ignition[958]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:12:57.261148 ignition[958]: DEBUG : files: compiled without relabeling support, skipping May 16 00:12:57.271386 ignition[958]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 16 00:12:57.271386 ignition[958]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 16 00:12:57.281856 ignition[958]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 16 00:12:57.285886 ignition[958]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 16 00:12:57.289856 ignition[958]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 16 00:12:57.289856 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar-cgroupv1" May 16 00:12:57.289856 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar-cgroupv1" May 16 00:12:57.289856 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/home/core/install.sh" May 16 00:12:57.287101 unknown[958]: wrote ssh authorized keys file for user: core May 16 00:12:57.307200 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/home/core/install.sh" May 16 00:12:57.307200 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/flatcar/update.conf" May 16 00:12:57.307200 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 16 00:12:57.307200 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" May 16 00:12:57.307200 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" May 16 00:12:57.307200 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" May 16 00:12:57.307200 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-x86-64.raw: attempt #1 May 16 00:12:57.907604 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(7): GET result: OK May 16 00:12:58.337026 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" May 16 00:12:58.337026 ignition[958]: INFO : files: op(8): [started] processing unit "containerd.service" May 16 00:12:58.341688 ignition[958]: INFO : files: op(8): op(9): [started] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" May 16 00:12:58.341688 ignition[958]: INFO : files: op(8): op(9): [finished] writing systemd drop-in "10-use-cgroupfs.conf" at "/sysroot/etc/systemd/system/containerd.service.d/10-use-cgroupfs.conf" May 16 00:12:58.341688 ignition[958]: INFO : files: op(8): [finished] processing unit "containerd.service" May 16 00:12:58.341688 ignition[958]: INFO : files: op(a): [started] processing unit "coreos-metadata.service" May 16 00:12:58.341688 ignition[958]: INFO : files: op(a): op(b): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 16 00:12:58.341688 ignition[958]: INFO : files: op(a): op(b): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 16 00:12:58.341688 ignition[958]: INFO : files: op(a): [finished] processing unit "coreos-metadata.service" May 16 00:12:58.341688 ignition[958]: INFO : files: op(c): [started] setting preset to disabled for "coreos-metadata.service" May 16 00:12:58.373014 ignition[958]: INFO : files: op(c): op(d): [started] removing enablement symlink(s) for "coreos-metadata.service" May 16 00:12:58.380881 ignition[958]: INFO : files: op(c): op(d): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 16 00:12:58.382824 ignition[958]: INFO : files: op(c): [finished] setting preset to disabled for "coreos-metadata.service" May 16 00:12:58.382824 ignition[958]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" May 16 00:12:58.382824 ignition[958]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" May 16 00:12:58.382824 ignition[958]: INFO : files: files passed May 16 00:12:58.382824 ignition[958]: INFO : Ignition finished successfully May 16 00:12:58.383932 systemd[1]: Finished ignition-files.service - Ignition (files). May 16 00:12:58.400767 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 16 00:12:58.403543 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 16 00:12:58.405581 systemd[1]: ignition-quench.service: Deactivated successfully. May 16 00:12:58.405693 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 16 00:12:58.413833 initrd-setup-root-after-ignition[986]: grep: /sysroot/oem/oem-release: No such file or directory May 16 00:12:58.417103 initrd-setup-root-after-ignition[988]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 16 00:12:58.418810 initrd-setup-root-after-ignition[988]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 16 00:12:58.420404 initrd-setup-root-after-ignition[992]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 16 00:12:58.419371 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 16 00:12:58.422413 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 16 00:12:58.433821 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 16 00:12:58.470366 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 16 00:12:58.470553 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 16 00:12:58.473328 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 16 00:12:58.475629 systemd[1]: Reached target initrd.target - Initrd Default Target. May 16 00:12:58.477944 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 16 00:12:58.479185 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 16 00:12:58.502597 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 16 00:12:58.520845 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 16 00:12:58.534938 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 16 00:12:58.537481 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 16 00:12:58.539927 systemd[1]: Stopped target timers.target - Timer Units. May 16 00:12:58.541851 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 16 00:12:58.542906 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 16 00:12:58.545559 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 16 00:12:58.547781 systemd[1]: Stopped target basic.target - Basic System. May 16 00:12:58.549667 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 16 00:12:58.551954 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 16 00:12:58.554315 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 16 00:12:58.556604 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 16 00:12:58.559068 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 16 00:12:58.561763 systemd[1]: Stopped target sysinit.target - System Initialization. May 16 00:12:58.564093 systemd[1]: Stopped target local-fs.target - Local File Systems. May 16 00:12:58.566333 systemd[1]: Stopped target swap.target - Swaps. May 16 00:12:58.568066 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 16 00:12:58.569155 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 16 00:12:58.571476 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 16 00:12:58.573708 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 16 00:12:58.576265 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 16 00:12:58.578993 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 16 00:12:58.580552 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 16 00:12:58.580713 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 16 00:12:58.593049 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 16 00:12:58.595844 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 16 00:12:58.598978 systemd[1]: Stopped target paths.target - Path Units. May 16 00:12:58.600894 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 16 00:12:58.604583 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 16 00:12:58.607400 systemd[1]: Stopped target slices.target - Slice Units. May 16 00:12:58.609269 systemd[1]: Stopped target sockets.target - Socket Units. May 16 00:12:58.611179 systemd[1]: iscsid.socket: Deactivated successfully. May 16 00:12:58.612074 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 16 00:12:58.614046 systemd[1]: iscsiuio.socket: Deactivated successfully. May 16 00:12:58.614947 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 16 00:12:58.617057 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 16 00:12:58.618359 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 16 00:12:58.621080 systemd[1]: ignition-files.service: Deactivated successfully. May 16 00:12:58.622164 systemd[1]: Stopped ignition-files.service - Ignition (files). May 16 00:12:58.638735 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 16 00:12:58.641503 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 16 00:12:58.642475 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 16 00:12:58.643655 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 16 00:12:58.647148 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 16 00:12:58.648327 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 16 00:12:58.651613 ignition[1012]: INFO : Ignition 2.20.0 May 16 00:12:58.651613 ignition[1012]: INFO : Stage: umount May 16 00:12:58.653561 ignition[1012]: INFO : no configs at "/usr/lib/ignition/base.d" May 16 00:12:58.653561 ignition[1012]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 16 00:12:58.653561 ignition[1012]: INFO : umount: umount passed May 16 00:12:58.653561 ignition[1012]: INFO : Ignition finished successfully May 16 00:12:58.658979 systemd[1]: ignition-mount.service: Deactivated successfully. May 16 00:12:58.660143 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 16 00:12:58.663867 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 16 00:12:58.664924 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 16 00:12:58.669017 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 16 00:12:58.669397 systemd[1]: Stopped target network.target - Network. May 16 00:12:58.670403 systemd[1]: ignition-disks.service: Deactivated successfully. May 16 00:12:58.670456 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 16 00:12:58.701898 systemd[1]: ignition-kargs.service: Deactivated successfully. May 16 00:12:58.703877 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 16 00:12:58.706980 systemd[1]: ignition-setup.service: Deactivated successfully. May 16 00:12:58.707050 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 16 00:12:58.709995 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 16 00:12:58.710989 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 16 00:12:58.713312 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 16 00:12:58.715671 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 16 00:12:58.720588 systemd-networkd[772]: eth0: DHCPv6 lease lost May 16 00:12:58.723307 systemd[1]: systemd-networkd.service: Deactivated successfully. May 16 00:12:58.723467 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 16 00:12:58.725858 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 16 00:12:58.725904 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 16 00:12:58.734705 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 16 00:12:58.735181 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 16 00:12:58.735250 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 16 00:12:58.737268 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 16 00:12:58.737892 systemd[1]: systemd-resolved.service: Deactivated successfully. May 16 00:12:58.738051 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 16 00:12:58.743900 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 16 00:12:58.743979 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 16 00:12:58.744950 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 16 00:12:58.745011 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 16 00:12:58.747003 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 16 00:12:58.747070 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 16 00:12:58.761994 systemd[1]: systemd-udevd.service: Deactivated successfully. May 16 00:12:58.762206 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 16 00:12:58.764221 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 16 00:12:58.764302 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 16 00:12:58.766320 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 16 00:12:58.766370 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 16 00:12:58.768337 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 16 00:12:58.768397 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 16 00:12:58.771539 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 16 00:12:58.771603 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 16 00:12:58.773555 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 16 00:12:58.773620 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 16 00:12:58.776942 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 16 00:12:58.778413 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 16 00:12:58.778478 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 16 00:12:58.780870 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 16 00:12:58.780930 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 16 00:12:58.783225 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 16 00:12:58.783287 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 16 00:12:58.785685 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 16 00:12:58.785748 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 16 00:12:58.788571 systemd[1]: network-cleanup.service: Deactivated successfully. May 16 00:12:58.788709 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 16 00:12:58.791068 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 16 00:12:58.791195 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 16 00:12:58.954914 systemd[1]: sysroot-boot.service: Deactivated successfully. May 16 00:12:58.955076 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 16 00:12:58.958035 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 16 00:12:58.959378 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 16 00:12:58.959453 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 16 00:12:58.977803 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 16 00:12:58.984544 systemd[1]: Switching root. May 16 00:12:59.019861 systemd-journald[194]: Journal stopped May 16 00:13:01.344928 systemd-journald[194]: Received SIGTERM from PID 1 (systemd). May 16 00:13:01.345009 kernel: SELinux: policy capability network_peer_controls=1 May 16 00:13:01.345037 kernel: SELinux: policy capability open_perms=1 May 16 00:13:01.345051 kernel: SELinux: policy capability extended_socket_class=1 May 16 00:13:01.345065 kernel: SELinux: policy capability always_check_network=0 May 16 00:13:01.345080 kernel: SELinux: policy capability cgroup_seclabel=1 May 16 00:13:01.345093 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 16 00:13:01.345107 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 16 00:13:01.345125 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 16 00:13:01.345140 kernel: audit: type=1403 audit(1747354379.908:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 16 00:13:01.345160 systemd[1]: Successfully loaded SELinux policy in 64.302ms. May 16 00:13:01.345190 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 18.682ms. May 16 00:13:01.345209 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) May 16 00:13:01.345224 systemd[1]: Detected virtualization kvm. May 16 00:13:01.345241 systemd[1]: Detected architecture x86-64. May 16 00:13:01.345256 systemd[1]: Detected first boot. May 16 00:13:01.345271 systemd[1]: Initializing machine ID from VM UUID. May 16 00:13:01.345287 zram_generator::config[1074]: No configuration found. May 16 00:13:01.345303 systemd[1]: Populated /etc with preset unit settings. May 16 00:13:01.345320 systemd[1]: Queued start job for default target multi-user.target. May 16 00:13:01.345341 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 16 00:13:01.345357 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 16 00:13:01.345375 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 16 00:13:01.345390 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 16 00:13:01.345405 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 16 00:13:01.345420 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 16 00:13:01.345435 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 16 00:13:01.345450 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 16 00:13:01.345465 systemd[1]: Created slice user.slice - User and Session Slice. May 16 00:13:01.345480 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 16 00:13:01.345495 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 16 00:13:01.345512 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 16 00:13:01.346239 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 16 00:13:01.346278 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 16 00:13:01.346295 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 16 00:13:01.346309 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 16 00:13:01.346323 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 16 00:13:01.346338 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 16 00:13:01.346352 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 16 00:13:01.346366 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 16 00:13:01.346389 systemd[1]: Reached target slices.target - Slice Units. May 16 00:13:01.346403 systemd[1]: Reached target swap.target - Swaps. May 16 00:13:01.346417 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 16 00:13:01.346431 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 16 00:13:01.346445 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 16 00:13:01.346459 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 16 00:13:01.346475 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 16 00:13:01.346488 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 16 00:13:01.346505 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 16 00:13:01.346520 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 16 00:13:01.346552 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 16 00:13:01.346568 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 16 00:13:01.346582 systemd[1]: Mounting media.mount - External Media Directory... May 16 00:13:01.346597 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:01.346611 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 16 00:13:01.346698 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 16 00:13:01.346716 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 16 00:13:01.346730 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 16 00:13:01.346744 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 16 00:13:01.346758 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 16 00:13:01.346772 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 16 00:13:01.346786 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 16 00:13:01.346799 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 16 00:13:01.346813 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 16 00:13:01.346827 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 16 00:13:01.346844 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 16 00:13:01.346858 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 16 00:13:01.346873 systemd[1]: systemd-journald.service: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling. May 16 00:13:01.346887 systemd[1]: systemd-journald.service: (This warning is only shown for the first unit using IP firewalling.) May 16 00:13:01.346911 kernel: fuse: init (API version 7.39) May 16 00:13:01.346926 kernel: loop: module loaded May 16 00:13:01.346939 systemd[1]: Starting systemd-journald.service - Journal Service... May 16 00:13:01.346964 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 16 00:13:01.346981 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 16 00:13:01.346995 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 16 00:13:01.347009 kernel: ACPI: bus type drm_connector registered May 16 00:13:01.347056 systemd-journald[1156]: Collecting audit messages is disabled. May 16 00:13:01.347084 systemd-journald[1156]: Journal started May 16 00:13:01.347277 systemd-journald[1156]: Runtime Journal (/run/log/journal/761a21b505cd4e0fba26d84c08b17300) is 6.0M, max 48.4M, 42.3M free. May 16 00:13:01.368860 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 16 00:13:01.381552 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:01.388020 systemd[1]: Started systemd-journald.service - Journal Service. May 16 00:13:01.392179 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 16 00:13:01.395555 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 16 00:13:01.403277 systemd[1]: Mounted media.mount - External Media Directory. May 16 00:13:01.408246 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 16 00:13:01.409824 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 16 00:13:01.412743 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 16 00:13:01.417013 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 16 00:13:01.419456 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 16 00:13:01.419886 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 16 00:13:01.427419 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:13:01.428825 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 16 00:13:01.457093 systemd[1]: modprobe@drm.service: Deactivated successfully. May 16 00:13:01.458519 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 16 00:13:01.460401 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:13:01.460709 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 16 00:13:01.462718 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 16 00:13:01.463022 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 16 00:13:01.464820 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:13:01.466664 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 16 00:13:01.470381 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 16 00:13:01.476596 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 16 00:13:01.481286 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 16 00:13:01.499085 systemd[1]: Reached target network-pre.target - Preparation for Network. May 16 00:13:01.511783 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 16 00:13:01.519587 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 16 00:13:01.521397 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 16 00:13:01.525176 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 16 00:13:01.554167 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 16 00:13:01.556029 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 16 00:13:01.559549 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 16 00:13:01.561503 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 16 00:13:01.566767 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 16 00:13:01.571242 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 16 00:13:01.572714 systemd-journald[1156]: Time spent on flushing to /var/log/journal/761a21b505cd4e0fba26d84c08b17300 is 25.322ms for 922 entries. May 16 00:13:01.572714 systemd-journald[1156]: System Journal (/var/log/journal/761a21b505cd4e0fba26d84c08b17300) is 8.0M, max 195.6M, 187.6M free. May 16 00:13:02.055396 systemd-journald[1156]: Received client request to flush runtime journal. May 16 00:13:01.585064 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 16 00:13:01.590794 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 16 00:13:01.622211 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 16 00:13:01.661722 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 16 00:13:01.677813 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 16 00:13:01.712496 udevadm[1218]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. May 16 00:13:01.740052 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 16 00:13:01.867321 systemd-tmpfiles[1211]: ACLs are not supported, ignoring. May 16 00:13:01.867447 systemd-tmpfiles[1211]: ACLs are not supported, ignoring. May 16 00:13:01.883685 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 16 00:13:01.900003 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 16 00:13:01.911038 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 16 00:13:01.933136 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 16 00:13:02.073053 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 16 00:13:02.145567 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 16 00:13:02.165879 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 16 00:13:02.219838 systemd-tmpfiles[1233]: ACLs are not supported, ignoring. May 16 00:13:02.219864 systemd-tmpfiles[1233]: ACLs are not supported, ignoring. May 16 00:13:02.228661 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 16 00:13:03.183997 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 16 00:13:03.200862 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 16 00:13:03.237588 systemd-udevd[1239]: Using default interface naming scheme 'v255'. May 16 00:13:03.258696 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 16 00:13:03.271292 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 16 00:13:03.293719 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 16 00:13:03.310489 systemd[1]: Found device dev-ttyS0.device - /dev/ttyS0. May 16 00:13:03.364368 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1250) May 16 00:13:03.371950 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 16 00:13:03.438518 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 16 00:13:03.443592 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) May 16 00:13:03.443856 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD May 16 00:13:03.444060 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 May 16 00:13:03.444084 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 May 16 00:13:03.458592 kernel: ACPI: button: Power Button [PWRF] May 16 00:13:03.496510 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 16 00:13:03.507864 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 16 00:13:03.634561 systemd-networkd[1245]: lo: Link UP May 16 00:13:03.634574 systemd-networkd[1245]: lo: Gained carrier May 16 00:13:03.636700 systemd-networkd[1245]: Enumeration completed May 16 00:13:03.636911 systemd[1]: Started systemd-networkd.service - Network Configuration. May 16 00:13:03.638943 systemd-networkd[1245]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 16 00:13:03.638955 systemd-networkd[1245]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 16 00:13:03.639829 systemd-networkd[1245]: eth0: Link UP May 16 00:13:03.639840 systemd-networkd[1245]: eth0: Gained carrier May 16 00:13:03.639853 systemd-networkd[1245]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 16 00:13:03.641593 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 16 00:13:03.712651 kernel: mousedev: PS/2 mouse device common for all mice May 16 00:13:03.712129 systemd-networkd[1245]: eth0: DHCPv4 address 10.0.0.12/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 16 00:13:03.729039 kernel: kvm_amd: TSC scaling supported May 16 00:13:03.729150 kernel: kvm_amd: Nested Virtualization enabled May 16 00:13:03.729167 kernel: kvm_amd: Nested Paging enabled May 16 00:13:03.730147 kernel: kvm_amd: LBR virtualization supported May 16 00:13:03.732129 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported May 16 00:13:03.732253 kernel: kvm_amd: Virtual GIF supported May 16 00:13:03.796564 kernel: EDAC MC: Ver: 3.0.0 May 16 00:13:03.838449 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 16 00:13:03.844589 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 16 00:13:03.860693 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 16 00:13:03.885165 lvm[1286]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 16 00:13:03.928122 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 16 00:13:03.929967 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 16 00:13:03.941680 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 16 00:13:03.948847 lvm[1289]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 16 00:13:03.988382 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 16 00:13:03.999876 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 16 00:13:04.002357 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). May 16 00:13:04.002406 systemd[1]: Reached target local-fs.target - Local File Systems. May 16 00:13:04.003714 systemd[1]: Reached target machines.target - Containers. May 16 00:13:04.006504 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). May 16 00:13:04.021776 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 16 00:13:04.033890 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 16 00:13:04.035427 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 16 00:13:04.036909 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 16 00:13:04.040524 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... May 16 00:13:04.044968 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 16 00:13:04.048243 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 16 00:13:04.058198 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 16 00:13:04.065570 kernel: loop0: detected capacity change from 0 to 221472 May 16 00:13:04.120594 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 16 00:13:04.148553 kernel: loop1: detected capacity change from 0 to 138184 May 16 00:13:04.252572 kernel: loop2: detected capacity change from 0 to 140992 May 16 00:13:04.344668 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 16 00:13:04.346027 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. May 16 00:13:04.368555 kernel: loop3: detected capacity change from 0 to 221472 May 16 00:13:04.381566 kernel: loop4: detected capacity change from 0 to 138184 May 16 00:13:04.392550 kernel: loop5: detected capacity change from 0 to 140992 May 16 00:13:04.400436 (sd-merge)[1309]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 16 00:13:04.401143 (sd-merge)[1309]: Merged extensions into '/usr'. May 16 00:13:04.405820 systemd[1]: Reloading requested from client PID 1297 ('systemd-sysext') (unit systemd-sysext.service)... May 16 00:13:04.405840 systemd[1]: Reloading... May 16 00:13:04.554274 zram_generator::config[1339]: No configuration found. May 16 00:13:04.686131 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. May 16 00:13:04.729317 ldconfig[1294]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 16 00:13:04.777898 systemd[1]: Reloading finished in 371 ms. May 16 00:13:04.801260 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 16 00:13:04.803217 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 16 00:13:04.819923 systemd[1]: Starting ensure-sysext.service... May 16 00:13:04.827879 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 16 00:13:04.834197 systemd[1]: Reloading requested from client PID 1381 ('systemctl') (unit ensure-sysext.service)... May 16 00:13:04.834217 systemd[1]: Reloading... May 16 00:13:04.878899 systemd-tmpfiles[1382]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 16 00:13:04.879362 systemd-tmpfiles[1382]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 16 00:13:04.880493 systemd-tmpfiles[1382]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 16 00:13:04.880805 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. May 16 00:13:04.880899 systemd-tmpfiles[1382]: ACLs are not supported, ignoring. May 16 00:13:04.885194 systemd-tmpfiles[1382]: Detected autofs mount point /boot during canonicalization of boot. May 16 00:13:04.885288 systemd-tmpfiles[1382]: Skipping /boot May 16 00:13:04.895598 zram_generator::config[1413]: No configuration found. May 16 00:13:04.905703 systemd-tmpfiles[1382]: Detected autofs mount point /boot during canonicalization of boot. May 16 00:13:04.905886 systemd-tmpfiles[1382]: Skipping /boot May 16 00:13:04.923740 systemd-networkd[1245]: eth0: Gained IPv6LL May 16 00:13:05.114947 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. May 16 00:13:05.192608 systemd[1]: Reloading finished in 357 ms. May 16 00:13:05.213453 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 16 00:13:05.225145 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 16 00:13:05.234497 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 16 00:13:05.248211 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 16 00:13:05.251647 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 16 00:13:05.257877 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 16 00:13:05.265929 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 16 00:13:05.272096 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:05.272310 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 16 00:13:05.274945 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 16 00:13:05.282026 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 16 00:13:05.286224 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 16 00:13:05.288700 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 16 00:13:05.288949 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:05.290209 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 16 00:13:05.292696 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:13:05.293026 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 16 00:13:05.295366 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:13:05.295614 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 16 00:13:05.300828 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:13:05.301112 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 16 00:13:05.308686 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:05.309187 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 16 00:13:05.317501 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 16 00:13:05.321810 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 16 00:13:05.327961 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 16 00:13:05.330036 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 16 00:13:05.331793 augenrules[1497]: No rules May 16 00:13:05.334918 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 16 00:13:05.336095 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:05.338324 systemd[1]: audit-rules.service: Deactivated successfully. May 16 00:13:05.338648 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 16 00:13:05.340906 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 16 00:13:05.343270 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:13:05.343502 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 16 00:13:05.345457 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:13:05.345695 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 16 00:13:05.348159 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:13:05.348400 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 16 00:13:05.353435 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 16 00:13:05.355603 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 16 00:13:05.362834 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 16 00:13:05.363008 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 16 00:13:05.363131 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). May 16 00:13:05.365705 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:05.371662 systemd-resolved[1461]: Positive Trust Anchors: May 16 00:13:05.371683 systemd-resolved[1461]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 16 00:13:05.371720 systemd-resolved[1461]: 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 May 16 00:13:05.373759 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 16 00:13:05.375020 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 16 00:13:05.375376 systemd-resolved[1461]: Defaulting to hostname 'linux'. May 16 00:13:05.377153 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 16 00:13:05.380442 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 16 00:13:05.382736 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 16 00:13:05.387771 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 16 00:13:05.389106 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 16 00:13:05.389252 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). May 16 00:13:05.389336 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 16 00:13:05.390397 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 16 00:13:05.393861 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 16 00:13:05.394105 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 16 00:13:05.396091 systemd[1]: modprobe@drm.service: Deactivated successfully. May 16 00:13:05.396321 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 16 00:13:05.398027 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 16 00:13:05.398255 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 16 00:13:05.400128 systemd[1]: modprobe@loop.service: Deactivated successfully. May 16 00:13:05.400426 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 16 00:13:05.404681 systemd[1]: Finished ensure-sysext.service. May 16 00:13:05.407522 augenrules[1518]: /sbin/augenrules: No change May 16 00:13:05.411204 systemd[1]: Reached target network.target - Network. May 16 00:13:05.412352 systemd[1]: Reached target network-online.target - Network is Online. May 16 00:13:05.413625 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 16 00:13:05.415075 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 16 00:13:05.415144 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 16 00:13:05.415452 augenrules[1550]: No rules May 16 00:13:05.428897 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 16 00:13:05.430949 systemd[1]: audit-rules.service: Deactivated successfully. May 16 00:13:05.431325 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 16 00:13:05.499522 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 16 00:13:05.501447 systemd[1]: Reached target sysinit.target - System Initialization. May 16 00:13:06.166275 systemd-timesyncd[1556]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 16 00:13:06.166331 systemd-timesyncd[1556]: Initial clock synchronization to Fri 2025-05-16 00:13:06.166162 UTC. May 16 00:13:06.167136 systemd-resolved[1461]: Clock change detected. Flushing caches. May 16 00:13:06.167219 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 16 00:13:06.168671 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 16 00:13:06.170124 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 16 00:13:06.171567 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 16 00:13:06.171600 systemd[1]: Reached target paths.target - Path Units. May 16 00:13:06.172655 systemd[1]: Reached target time-set.target - System Time Set. May 16 00:13:06.174059 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 16 00:13:06.175508 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 16 00:13:06.177044 systemd[1]: Reached target timers.target - Timer Units. May 16 00:13:06.179087 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 16 00:13:06.183056 systemd[1]: Starting docker.socket - Docker Socket for the API... May 16 00:13:06.185834 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 16 00:13:06.194336 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 16 00:13:06.195957 systemd[1]: Reached target sockets.target - Socket Units. May 16 00:13:06.197361 systemd[1]: Reached target basic.target - Basic System. May 16 00:13:06.198884 systemd[1]: System is tainted: cgroupsv1 May 16 00:13:06.198931 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 16 00:13:06.198957 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 16 00:13:06.201010 systemd[1]: Starting containerd.service - containerd container runtime... May 16 00:13:06.204343 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 16 00:13:06.207532 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 16 00:13:06.212889 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 16 00:13:06.219067 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 16 00:13:06.220760 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). May 16 00:13:06.225156 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 16 00:13:06.225593 jq[1565]: false May 16 00:13:06.230618 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 16 00:13:06.236128 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 16 00:13:06.244048 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 16 00:13:06.246706 extend-filesystems[1567]: Found loop3 May 16 00:13:06.249840 extend-filesystems[1567]: Found loop4 May 16 00:13:06.249840 extend-filesystems[1567]: Found loop5 May 16 00:13:06.249840 extend-filesystems[1567]: Found sr0 May 16 00:13:06.249840 extend-filesystems[1567]: Found vda May 16 00:13:06.249840 extend-filesystems[1567]: Found vda1 May 16 00:13:06.249840 extend-filesystems[1567]: Found vda2 May 16 00:13:06.249840 extend-filesystems[1567]: Found vda3 May 16 00:13:06.249840 extend-filesystems[1567]: Found usr May 16 00:13:06.249840 extend-filesystems[1567]: Found vda4 May 16 00:13:06.249840 extend-filesystems[1567]: Found vda6 May 16 00:13:06.249840 extend-filesystems[1567]: Found vda7 May 16 00:13:06.249840 extend-filesystems[1567]: Found vda9 May 16 00:13:06.249840 extend-filesystems[1567]: Checking size of /dev/vda9 May 16 00:13:06.250381 dbus-daemon[1563]: [system] SELinux support is enabled May 16 00:13:06.256021 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 16 00:13:06.265987 systemd[1]: Starting systemd-logind.service - User Login Management... May 16 00:13:06.267250 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 16 00:13:06.273971 systemd[1]: Starting update-engine.service - Update Engine... May 16 00:13:06.278545 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 16 00:13:06.281577 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 16 00:13:06.288680 jq[1593]: true May 16 00:13:06.289431 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 16 00:13:06.289820 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 16 00:13:06.300390 extend-filesystems[1567]: Resized partition /dev/vda9 May 16 00:13:06.303071 extend-filesystems[1603]: resize2fs 1.47.1 (20-May-2024) May 16 00:13:06.310163 update_engine[1590]: I20250516 00:13:06.304675 1590 main.cc:92] Flatcar Update Engine starting May 16 00:13:06.305512 systemd[1]: motdgen.service: Deactivated successfully. May 16 00:13:06.309013 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 16 00:13:06.314265 update_engine[1590]: I20250516 00:13:06.310771 1590 update_check_scheduler.cc:74] Next update check in 11m10s May 16 00:13:06.311443 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 16 00:13:06.313620 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 16 00:13:06.314927 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 16 00:13:06.322822 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1242) May 16 00:13:06.329193 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 16 00:13:06.341729 jq[1605]: true May 16 00:13:06.346899 (ntainerd)[1608]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 16 00:13:06.351735 systemd[1]: coreos-metadata.service: Deactivated successfully. May 16 00:13:06.352172 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 16 00:13:06.384276 systemd[1]: Started update-engine.service - Update Engine. May 16 00:13:06.386048 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 16 00:13:06.386210 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). May 16 00:13:06.386243 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 16 00:13:06.387671 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). May 16 00:13:06.387701 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 16 00:13:06.390308 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 16 00:13:06.397094 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 16 00:13:06.529503 systemd-logind[1587]: Watching system buttons on /dev/input/event2 (Power Button) May 16 00:13:06.529535 systemd-logind[1587]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 16 00:13:06.535840 systemd-logind[1587]: New seat seat0. May 16 00:13:06.548623 sshd_keygen[1600]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 16 00:13:06.545955 systemd[1]: Started systemd-logind.service - User Login Management. May 16 00:13:06.553199 locksmithd[1639]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 16 00:13:06.581420 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 16 00:13:06.597300 systemd[1]: Starting issuegen.service - Generate /run/issue... May 16 00:13:06.609639 systemd[1]: issuegen.service: Deactivated successfully. May 16 00:13:06.610137 systemd[1]: Finished issuegen.service - Generate /run/issue. May 16 00:13:06.615887 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 16 00:13:06.633271 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 16 00:13:06.711243 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 16 00:13:06.748397 systemd[1]: Started getty@tty1.service - Getty on tty1. May 16 00:13:06.755601 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 16 00:13:06.757934 systemd[1]: Reached target getty.target - Login Prompts. May 16 00:13:06.985334 extend-filesystems[1603]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 16 00:13:06.985334 extend-filesystems[1603]: old_desc_blocks = 1, new_desc_blocks = 1 May 16 00:13:06.985334 extend-filesystems[1603]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 16 00:13:06.990044 extend-filesystems[1567]: Resized filesystem in /dev/vda9 May 16 00:13:06.993167 systemd[1]: extend-filesystems.service: Deactivated successfully. May 16 00:13:06.993596 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 16 00:13:07.037526 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 16 00:13:07.044127 systemd[1]: Started sshd@0-10.0.0.12:22-10.0.0.1:54182.service - OpenSSH per-connection server daemon (10.0.0.1:54182). May 16 00:13:07.075501 bash[1638]: Updated "/home/core/.ssh/authorized_keys" May 16 00:13:07.106505 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 16 00:13:07.109515 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 16 00:13:07.203506 sshd[1671]: Accepted publickey for core from 10.0.0.1 port 54182 ssh2: RSA SHA256:QY1G9frpQwU38YRXjmF5CsEgUjbXQH+aZz1Ve5/ktwk May 16 00:13:07.205770 sshd-session[1671]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 16 00:13:07.216507 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 16 00:13:07.231158 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 16 00:13:07.237400 systemd-logind[1587]: New session 1 of user core. May 16 00:13:07.312444 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 16 00:13:07.325216 containerd[1608]: time="2025-05-16T00:13:07.325132635Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.388549477Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.400445140Z" 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.90-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.400489874Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.400524539Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.400718383Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.400735234Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.400817599Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.400830553Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.401146405Z" 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 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.401161223Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 16 00:13:07.407190 containerd[1608]: time="2025-05-16T00:13:07.401175189Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 16 00:13:07.407468 containerd[1608]: time="2025-05-16T00:13:07.401185549Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 16 00:13:07.407468 containerd[1608]: time="2025-05-16T00:13:07.401286969Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 16 00:13:07.407468 containerd[1608]: time="2025-05-16T00:13:07.401554992Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 16 00:13:07.407468 containerd[1608]: time="2025-05-16T00:13:07.401735380Z" 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 May 16 00:13:07.407468 containerd[1608]: time="2025-05-16T00:13:07.401748795Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 16 00:13:07.407468 containerd[1608]: time="2025-05-16T00:13:07.401860194Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 16 00:13:07.407468 containerd[1608]: time="2025-05-16T00:13:07.401914276Z" level=info msg="metadata content store policy set" policy=shared May 16 00:13:07.407204 systemd[1]: Starting user@500.service - User Manager for UID 500... May 16 00:13:07.439756 (systemd)[1680]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 16 00:13:07.659983 systemd[1680]: Queued start job for default target default.target. May 16 00:13:07.660402 systemd[1680]: Created slice app.slice - User Application Slice. May 16 00:13:07.660420 systemd[1680]: Reached target paths.target - Paths. May 16 00:13:07.660433 systemd[1680]: Reached target timers.target - Timers. May 16 00:13:07.673888 systemd[1680]: Starting dbus.socket - D-Bus User Message Bus Socket... May 16 00:13:07.683899 systemd[1680]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 16 00:13:07.683991 systemd[1680]: Reached target sockets.target - Sockets. May 16 00:13:07.684008 systemd[1680]: Reached target basic.target - Basic System. May 16 00:13:07.684055 systemd[1680]: Reached target default.target - Main User Target. May 16 00:13:07.684107 systemd[1680]: Startup finished in 224ms. May 16 00:13:07.684741 systemd[1]: Started user@500.service - User Manager for UID 500. May 16 00:13:07.730528 containerd[1608]: time="2025-05-16T00:13:07.730437391Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 16 00:13:07.730677 containerd[1608]: time="2025-05-16T00:13:07.730546816Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 16 00:13:07.730677 containerd[1608]: time="2025-05-16T00:13:07.730574478Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 16 00:13:07.730677 containerd[1608]: time="2025-05-16T00:13:07.730609654Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 16 00:13:07.730677 containerd[1608]: time="2025-05-16T00:13:07.730631686Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 16 00:13:07.731096 containerd[1608]: time="2025-05-16T00:13:07.730935936Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 16 00:13:07.731309 containerd[1608]: time="2025-05-16T00:13:07.731289620Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 16 00:13:07.731478 containerd[1608]: time="2025-05-16T00:13:07.731456222Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 16 00:13:07.731520 containerd[1608]: time="2025-05-16T00:13:07.731481089Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 16 00:13:07.731520 containerd[1608]: time="2025-05-16T00:13:07.731500696Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 16 00:13:07.731520 containerd[1608]: time="2025-05-16T00:13:07.731517167Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731603 containerd[1608]: time="2025-05-16T00:13:07.731533868Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731603 containerd[1608]: time="2025-05-16T00:13:07.731554096Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731603 containerd[1608]: time="2025-05-16T00:13:07.731569795Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731603 containerd[1608]: time="2025-05-16T00:13:07.731587308Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731603 containerd[1608]: time="2025-05-16T00:13:07.731599140Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731692 containerd[1608]: time="2025-05-16T00:13:07.731611493Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731692 containerd[1608]: time="2025-05-16T00:13:07.731623045Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 16 00:13:07.731692 containerd[1608]: time="2025-05-16T00:13:07.731643033Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731692 containerd[1608]: time="2025-05-16T00:13:07.731658652Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731692 containerd[1608]: time="2025-05-16T00:13:07.731670063Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731692 containerd[1608]: time="2025-05-16T00:13:07.731682226Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731692 containerd[1608]: time="2025-05-16T00:13:07.731693638Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731707694Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731721109Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731733472Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731746196Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731760323Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731770682Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731781783Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731793455Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731828010Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731854629Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731874797Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 16 00:13:07.731900 containerd[1608]: time="2025-05-16T00:13:07.731888884Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.731961650Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.731988220Z" 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 May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.732002998Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.732019208Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.732031491Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.732044025Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.732054845Z" level=info msg="NRI interface is disabled by configuration." May 16 00:13:07.732142 containerd[1608]: time="2025-05-16T00:13:07.732078930Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 16 00:13:07.732484 containerd[1608]: time="2025-05-16T00:13:07.732422064Z" 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:false] 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:false 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}" May 16 00:13:07.732484 containerd[1608]: time="2025-05-16T00:13:07.732480984Z" level=info msg="Connect containerd service" May 16 00:13:07.732679 containerd[1608]: time="2025-05-16T00:13:07.732514878Z" level=info msg="using legacy CRI server" May 16 00:13:07.732679 containerd[1608]: time="2025-05-16T00:13:07.732523745Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 16 00:13:07.732679 containerd[1608]: time="2025-05-16T00:13:07.732660140Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 16 00:13:07.733349 containerd[1608]: time="2025-05-16T00:13:07.733327763Z" 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" May 16 00:13:07.733561 containerd[1608]: time="2025-05-16T00:13:07.733486631Z" level=info msg="Start subscribing containerd event" May 16 00:13:07.733626 containerd[1608]: time="2025-05-16T00:13:07.733610924Z" level=info msg="Start recovering state" May 16 00:13:07.733721 containerd[1608]: time="2025-05-16T00:13:07.733701333Z" level=info msg="Start event monitor" May 16 00:13:07.733748 containerd[1608]: time="2025-05-16T00:13:07.733737752Z" level=info msg="Start snapshots syncer" May 16 00:13:07.733768 containerd[1608]: time="2025-05-16T00:13:07.733750997Z" level=info msg="Start cni network conf syncer for default" May 16 00:13:07.733768 containerd[1608]: time="2025-05-16T00:13:07.733760274Z" level=info msg="Start streaming server" May 16 00:13:07.733966 containerd[1608]: time="2025-05-16T00:13:07.733709048Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 16 00:13:07.734106 containerd[1608]: time="2025-05-16T00:13:07.734087738Z" level=info msg=serving... address=/run/containerd/containerd.sock May 16 00:13:07.734784 containerd[1608]: time="2025-05-16T00:13:07.734309544Z" level=info msg="containerd successfully booted in 0.410110s" May 16 00:13:07.737885 systemd[1]: Started session-1.scope - Session 1 of User core. May 16 00:13:07.740633 systemd[1]: Started containerd.service - containerd container runtime. May 16 00:13:07.798134 systemd[1]: Started sshd@1-10.0.0.12:22-10.0.0.1:54196.service - OpenSSH per-connection server daemon (10.0.0.1:54196). May 16 00:13:07.884534 sshd[1696]: Accepted publickey for core from 10.0.0.1 port 54196 ssh2: RSA SHA256:QY1G9frpQwU38YRXjmF5CsEgUjbXQH+aZz1Ve5/ktwk May 16 00:13:07.885297 sshd-session[1696]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 16 00:13:07.890006 systemd-logind[1587]: New session 2 of user core. May 16 00:13:07.898167 systemd[1]: Started session-2.scope - Session 2 of User core. May 16 00:13:07.954907 sshd[1699]: Connection closed by 10.0.0.1 port 54196 May 16 00:13:07.955399 sshd-session[1696]: pam_unix(sshd:session): session closed for user core May 16 00:13:07.972087 systemd[1]: Started sshd@2-10.0.0.12:22-10.0.0.1:54206.service - OpenSSH per-connection server daemon (10.0.0.1:54206). May 16 00:13:07.983444 systemd[1]: sshd@1-10.0.0.12:22-10.0.0.1:54196.service: Deactivated successfully. May 16 00:13:07.985616 systemd[1]: session-2.scope: Deactivated successfully. May 16 00:13:07.986343 systemd-logind[1587]: Session 2 logged out. Waiting for processes to exit. May 16 00:13:07.987833 systemd-logind[1587]: Removed session 2. May 16 00:13:08.011787 sshd[1702]: Accepted publickey for core from 10.0.0.1 port 54206 ssh2: RSA SHA256:QY1G9frpQwU38YRXjmF5CsEgUjbXQH+aZz1Ve5/ktwk May 16 00:13:08.013609 sshd-session[1702]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 16 00:13:08.018110 systemd-logind[1587]: New session 3 of user core. May 16 00:13:08.029118 systemd[1]: Started session-3.scope - Session 3 of User core. May 16 00:13:08.111480 sshd[1707]: Connection closed by 10.0.0.1 port 54206 May 16 00:13:08.111754 sshd-session[1702]: pam_unix(sshd:session): session closed for user core May 16 00:13:08.114954 systemd[1]: sshd@2-10.0.0.12:22-10.0.0.1:54206.service: Deactivated successfully. May 16 00:13:08.117094 systemd-logind[1587]: Session 3 logged out. Waiting for processes to exit. May 16 00:13:08.117099 systemd[1]: session-3.scope: Deactivated successfully. May 16 00:13:08.118104 systemd-logind[1587]: Removed session 3. May 16 00:13:08.364295 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 16 00:13:08.382975 systemd[1]: Reached target multi-user.target - Multi-User System. May 16 00:13:08.384486 systemd[1]: Startup finished in 8.934s (kernel) + 7.874s (userspace) = 16.808s. May 16 00:13:08.414391 (kubelet)[1720]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 16 00:13:08.836244 kubelet[1720]: E0516 00:13:08.836070 1720 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" May 16 00:13:08.839916 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 16 00:13:08.840201 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 16 00:13:18.131154 systemd[1]: Started sshd@3-10.0.0.12:22-10.0.0.1:38050.service - OpenSSH per-connection server daemon (10.0.0.1:38050). May 16 00:13:18.173973 sshd[1733]: Accepted publickey for core from 10.0.0.1 port 38050 ssh2: RSA SHA256:QY1G9frpQwU38YRXjmF5CsEgUjbXQH+aZz1Ve5/ktwk May 16 00:13:18.175993 sshd-session[1733]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 16 00:13:18.181034 systemd-logind[1587]: New session 4 of user core. May 16 00:13:18.191197 systemd[1]: Started session-4.scope - Session 4 of User core. May 16 00:13:18.244859 sshd[1736]: Connection closed by 10.0.0.1 port 38050 May 16 00:13:18.245291 sshd-session[1733]: pam_unix(sshd:session): session closed for user core May 16 00:13:18.254081 systemd[1]: Started sshd@4-10.0.0.12:22-10.0.0.1:38060.service - OpenSSH per-connection server daemon (10.0.0.1:38060). May 16 00:13:18.254664 systemd[1]: sshd@3-10.0.0.12:22-10.0.0.1:38050.service: Deactivated successfully. May 16 00:13:18.257565 systemd[1]: session-4.scope: Deactivated successfully. May 16 00:13:18.257715 systemd-logind[1587]: Session 4 logged out. Waiting for processes to exit. May 16 00:13:18.259663 systemd-logind[1587]: Removed session 4. May 16 00:13:18.299827 sshd[1738]: Accepted publickey for core from 10.0.0.1 port 38060 ssh2: RSA SHA256:QY1G9frpQwU38YRXjmF5CsEgUjbXQH+aZz1Ve5/ktwk May 16 00:13:18.301505 sshd-session[1738]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 16 00:13:18.306413 systemd-logind[1587]: New session 5 of user core. May 16 00:13:18.322184 systemd[1]: Started session-5.scope - Session 5 of User core. May 16 00:13:18.373367 sshd[1744]: Connection closed by 10.0.0.1 port 38060 May 16 00:13:18.373926 sshd-session[1738]: pam_unix(sshd:session): session closed for user core May 16 00:13:18.389208 systemd[1]: Started sshd@5-10.0.0.12:22-10.0.0.1:38068.service - OpenSSH per-connection server daemon (10.0.0.1:38068). May 16 00:13:18.389777 systemd[1]: sshd@4-10.0.0.12:22-10.0.0.1:38060.service: Deactivated successfully. May 16 00:13:18.391958 systemd-logind[1587]: Session 5 logged out. Waiting for processes to exit. May 16 00:13:18.392634 systemd[1]: session-5.scope: Deactivated successfully. May 16 00:13:18.394935 systemd-logind[1587]: Removed session 5. May 16 00:13:18.428785 sshd[1746]: Accepted publickey for core from 10.0.0.1 port 38068 ssh2: RSA SHA256:QY1G9frpQwU38YRXjmF5CsEgUjbXQH+aZz1Ve5/ktwk May 16 00:13:18.430557 sshd-session[1746]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 16 00:13:18.435224 systemd-logind[1587]: New session 6 of user core. May 16 00:13:18.445272 systemd[1]: Started session-6.scope - Session 6 of User core. May 16 00:13:18.499980 sshd[1752]: Connection closed by 10.0.0.1 port 38068 May 16 00:13:18.500282 sshd-session[1746]: pam_unix(sshd:session): session closed for user core May 16 00:13:18.513112 systemd[1]: Started sshd@6-10.0.0.12:22-10.0.0.1:38080.service - OpenSSH per-connection server daemon (10.0.0.1:38080). May 16 00:13:18.513810 systemd[1]: sshd@5-10.0.0.12:22-10.0.0.1:38068.service: Deactivated successfully. May 16 00:13:18.515675 systemd[1]: session-6.scope: Deactivated successfully. May 16 00:13:18.516495 systemd-logind[1587]: Session 6 logged out. Waiting for processes to exit. May 16 00:13:18.518127 systemd-logind[1587]: Removed session 6. May 16 00:13:18.553970 sshd[1755]: Accepted publickey for core from 10.0.0.1 port 38080 ssh2: RSA SHA256:QY1G9frpQwU38YRXjmF5CsEgUjbXQH+aZz1Ve5/ktwk May 16 00:13:18.555712 sshd-session[1755]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 16 00:13:18.560426 systemd-logind[1587]: New session 7 of user core. May 16 00:13:18.570171 systemd[1]: Started session-7.scope - Session 7 of User core. May 16 00:13:18.630455 sudo[1761]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 16 00:13:18.630900 sudo[1761]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 16 00:13:18.657216 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 16 00:13:18.678271 systemd[1]: coreos-metadata.service: Deactivated successfully. May 16 00:13:18.678688 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 16 00:13:19.057250 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. May 16 00:13:19.068028 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 16 00:13:19.249860 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM May 16 00:13:19.250051 systemd[1]: kubelet.service: Failed with result 'signal'. May 16 00:13:19.250655 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 16 00:13:19.264122 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 16 00:13:19.292318 systemd[1]: Reloading requested from client PID 1812 ('systemctl') (unit session-7.scope)... May 16 00:13:19.292339 systemd[1]: Reloading... May 16 00:13:19.374822 zram_generator::config[1850]: No configuration found. May 16 00:13:20.094871 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. May 16 00:13:20.176944 systemd[1]: Reloading finished in 884 ms. May 16 00:13:20.231763 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM May 16 00:13:20.231920 systemd[1]: kubelet.service: Failed with result 'signal'. May 16 00:13:20.232493 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 16 00:13:20.244296 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 16 00:13:20.412130 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 16 00:13:20.419288 (kubelet)[1910]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 16 00:13:20.464395 kubelet[1910]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. May 16 00:13:20.464395 kubelet[1910]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 16 00:13:20.464395 kubelet[1910]: 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. May 16 00:13:20.464906 kubelet[1910]: I0516 00:13:20.464485 1910 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 16 00:13:20.589488 kubelet[1910]: I0516 00:13:20.589428 1910 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" May 16 00:13:20.589488 kubelet[1910]: I0516 00:13:20.589476 1910 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 16 00:13:20.589844 kubelet[1910]: I0516 00:13:20.589819 1910 server.go:934] "Client rotation is on, will bootstrap in background" May 16 00:13:20.618938 kubelet[1910]: I0516 00:13:20.618888 1910 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 16 00:13:20.626459 kubelet[1910]: E0516 00:13:20.626411 1910 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 16 00:13:20.626459 kubelet[1910]: I0516 00:13:20.626441 1910 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 16 00:13:20.633298 kubelet[1910]: I0516 00:13:20.633262 1910 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 16 00:13:20.634312 kubelet[1910]: I0516 00:13:20.634285 1910 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 16 00:13:20.634502 kubelet[1910]: I0516 00:13:20.634457 1910 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 16 00:13:20.634669 kubelet[1910]: I0516 00:13:20.634495 1910 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.12","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"cgroupfs","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,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":1} May 16 00:13:20.634782 kubelet[1910]: I0516 00:13:20.634681 1910 topology_manager.go:138] "Creating topology manager with none policy" May 16 00:13:20.634782 kubelet[1910]: I0516 00:13:20.634692 1910 container_manager_linux.go:300] "Creating device plugin manager" May 16 00:13:20.634868 kubelet[1910]: I0516 00:13:20.634854 1910 state_mem.go:36] "Initialized new in-memory state store" May 16 00:13:20.636851 kubelet[1910]: I0516 00:13:20.636812 1910 kubelet.go:408] "Attempting to sync node with API server" May 16 00:13:20.636851 kubelet[1910]: I0516 00:13:20.636838 1910 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 16 00:13:20.636982 kubelet[1910]: I0516 00:13:20.636875 1910 kubelet.go:314] "Adding apiserver pod source" May 16 00:13:20.636982 kubelet[1910]: I0516 00:13:20.636893 1910 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 16 00:13:20.637018 kubelet[1910]: E0516 00:13:20.636991 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:20.637068 kubelet[1910]: E0516 00:13:20.637020 1910 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:20.638792 kubelet[1910]: I0516 00:13:20.638764 1910 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" May 16 00:13:20.639175 kubelet[1910]: I0516 00:13:20.639152 1910 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 16 00:13:20.639249 kubelet[1910]: W0516 00:13:20.639214 1910 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 16 00:13:20.641328 kubelet[1910]: W0516 00:13:20.641303 1910 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.12" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 16 00:13:20.641392 kubelet[1910]: E0516 00:13:20.641369 1910 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.12\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 16 00:13:20.641643 kubelet[1910]: W0516 00:13:20.641606 1910 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope May 16 00:13:20.641643 kubelet[1910]: E0516 00:13:20.641637 1910 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" May 16 00:13:20.641833 kubelet[1910]: I0516 00:13:20.641686 1910 server.go:1274] "Started kubelet" May 16 00:13:20.641930 kubelet[1910]: I0516 00:13:20.641892 1910 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 16 00:13:20.642408 kubelet[1910]: I0516 00:13:20.642382 1910 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 16 00:13:20.643074 kubelet[1910]: I0516 00:13:20.642460 1910 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 16 00:13:20.643616 kubelet[1910]: I0516 00:13:20.643233 1910 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 16 00:13:20.647527 kubelet[1910]: I0516 00:13:20.647126 1910 server.go:449] "Adding debug handlers to kubelet server" May 16 00:13:20.654832 kubelet[1910]: I0516 00:13:20.653103 1910 volume_manager.go:289] "Starting Kubelet Volume Manager" May 16 00:13:20.654832 kubelet[1910]: E0516 00:13:20.653501 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:20.654832 kubelet[1910]: I0516 00:13:20.653597 1910 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 16 00:13:20.654832 kubelet[1910]: I0516 00:13:20.654209 1910 desired_state_of_world_populator.go:147] "Desired state populator starts to run" May 16 00:13:20.654832 kubelet[1910]: I0516 00:13:20.654509 1910 reconciler.go:26] "Reconciler: start to sync state" May 16 00:13:20.656220 kubelet[1910]: I0516 00:13:20.656199 1910 factory.go:221] Registration of the systemd container factory successfully May 16 00:13:20.656501 kubelet[1910]: I0516 00:13:20.656462 1910 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory May 16 00:13:20.658728 kubelet[1910]: E0516 00:13:20.658691 1910 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 16 00:13:20.658920 kubelet[1910]: I0516 00:13:20.658890 1910 factory.go:221] Registration of the containerd container factory successfully May 16 00:13:20.659244 kubelet[1910]: W0516 00:13:20.659221 1910 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope May 16 00:13:20.659305 kubelet[1910]: E0516 00:13:20.659257 1910 reflector.go:158] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" May 16 00:13:20.665236 kubelet[1910]: E0516 00:13:20.662569 1910 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.12.183fd991caf5f910 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.12,UID:10.0.0.12,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.12,},FirstTimestamp:2025-05-16 00:13:20.641644816 +0000 UTC m=+0.217682040,LastTimestamp:2025-05-16 00:13:20.641644816 +0000 UTC m=+0.217682040,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.12,}" May 16 00:13:20.665521 kubelet[1910]: E0516 00:13:20.665411 1910 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.12\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" May 16 00:13:20.669603 kubelet[1910]: E0516 00:13:20.667142 1910 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.12.183fd991cb3e64a4 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.12,UID:10.0.0.12,APIVersion:,ResourceVersion:,FieldPath:,},Reason:CgroupV1,Message:Cgroup v1 support is in maintenance mode, please migrate to Cgroup v2.,Source:EventSource{Component:kubelet,Host:10.0.0.12,},FirstTimestamp:2025-05-16 00:13:20.646390948 +0000 UTC m=+0.222428172,LastTimestamp:2025-05-16 00:13:20.646390948 +0000 UTC m=+0.222428172,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.12,}" May 16 00:13:20.682588 kubelet[1910]: I0516 00:13:20.682454 1910 cpu_manager.go:214] "Starting CPU manager" policy="none" May 16 00:13:20.682588 kubelet[1910]: I0516 00:13:20.682481 1910 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 16 00:13:20.682588 kubelet[1910]: I0516 00:13:20.682501 1910 state_mem.go:36] "Initialized new in-memory state store" May 16 00:13:20.754143 kubelet[1910]: E0516 00:13:20.754075 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:20.854685 kubelet[1910]: E0516 00:13:20.854618 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:20.871181 kubelet[1910]: E0516 00:13:20.871100 1910 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.12\" not found" node="10.0.0.12" May 16 00:13:20.955289 kubelet[1910]: E0516 00:13:20.955158 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.055647 kubelet[1910]: E0516 00:13:21.055575 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.156659 kubelet[1910]: E0516 00:13:21.156584 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.257299 kubelet[1910]: E0516 00:13:21.257140 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.357737 kubelet[1910]: E0516 00:13:21.357639 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.458220 kubelet[1910]: E0516 00:13:21.458155 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.558760 kubelet[1910]: E0516 00:13:21.558717 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.592125 kubelet[1910]: I0516 00:13:21.592054 1910 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 16 00:13:21.637510 kubelet[1910]: E0516 00:13:21.637437 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:21.659405 kubelet[1910]: E0516 00:13:21.659338 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.760309 kubelet[1910]: E0516 00:13:21.760238 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.860838 kubelet[1910]: E0516 00:13:21.860646 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:21.961339 kubelet[1910]: E0516 00:13:21.961175 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:22.034366 kubelet[1910]: I0516 00:13:22.034312 1910 policy_none.go:49] "None policy: Start" May 16 00:13:22.035296 kubelet[1910]: I0516 00:13:22.035264 1910 memory_manager.go:170] "Starting memorymanager" policy="None" May 16 00:13:22.035296 kubelet[1910]: I0516 00:13:22.035293 1910 state_mem.go:35] "Initializing new in-memory state store" May 16 00:13:22.053893 kubelet[1910]: I0516 00:13:22.053835 1910 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 16 00:13:22.055187 kubelet[1910]: I0516 00:13:22.055144 1910 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 16 00:13:22.055187 kubelet[1910]: I0516 00:13:22.055190 1910 status_manager.go:217] "Starting to sync pod status with apiserver" May 16 00:13:22.055353 kubelet[1910]: I0516 00:13:22.055240 1910 kubelet.go:2321] "Starting kubelet main sync loop" May 16 00:13:22.055353 kubelet[1910]: E0516 00:13:22.055308 1910 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" May 16 00:13:22.062293 kubelet[1910]: E0516 00:13:22.062260 1910 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.12\" not found" May 16 00:13:22.065011 kubelet[1910]: E0516 00:13:22.064983 1910 csi_plugin.go:305] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.12" not found May 16 00:13:22.075990 kubelet[1910]: I0516 00:13:22.075951 1910 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 16 00:13:22.076248 kubelet[1910]: I0516 00:13:22.076232 1910 eviction_manager.go:189] "Eviction manager: starting control loop" May 16 00:13:22.076286 kubelet[1910]: I0516 00:13:22.076250 1910 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 16 00:13:22.077786 kubelet[1910]: I0516 00:13:22.077425 1910 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 16 00:13:22.078514 kubelet[1910]: E0516 00:13:22.078488 1910 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.12\" not found" May 16 00:13:22.177546 kubelet[1910]: I0516 00:13:22.177389 1910 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.12" May 16 00:13:22.232647 kubelet[1910]: I0516 00:13:22.232579 1910 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.12" May 16 00:13:22.457146 kubelet[1910]: I0516 00:13:22.457012 1910 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 16 00:13:22.457467 containerd[1608]: time="2025-05-16T00:13:22.457421213Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 16 00:13:22.457993 kubelet[1910]: I0516 00:13:22.457600 1910 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 16 00:13:22.638363 kubelet[1910]: I0516 00:13:22.638282 1910 apiserver.go:52] "Watching apiserver" May 16 00:13:22.638363 kubelet[1910]: E0516 00:13:22.638334 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:22.666490 kubelet[1910]: I0516 00:13:22.665956 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/faa8ff64-10fd-49f0-8707-d85eace420d3-flannel-cfg\") pod \"kube-flannel-ds-vdtb8\" (UID: \"faa8ff64-10fd-49f0-8707-d85eace420d3\") " pod="kube-flannel/kube-flannel-ds-vdtb8" May 16 00:13:22.666490 kubelet[1910]: I0516 00:13:22.666018 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/faa8ff64-10fd-49f0-8707-d85eace420d3-run\") pod \"kube-flannel-ds-vdtb8\" (UID: \"faa8ff64-10fd-49f0-8707-d85eace420d3\") " pod="kube-flannel/kube-flannel-ds-vdtb8" May 16 00:13:22.666490 kubelet[1910]: I0516 00:13:22.666114 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/faa8ff64-10fd-49f0-8707-d85eace420d3-cni-plugin\") pod \"kube-flannel-ds-vdtb8\" (UID: \"faa8ff64-10fd-49f0-8707-d85eace420d3\") " pod="kube-flannel/kube-flannel-ds-vdtb8" May 16 00:13:22.666490 kubelet[1910]: I0516 00:13:22.666156 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/faa8ff64-10fd-49f0-8707-d85eace420d3-cni\") pod \"kube-flannel-ds-vdtb8\" (UID: \"faa8ff64-10fd-49f0-8707-d85eace420d3\") " pod="kube-flannel/kube-flannel-ds-vdtb8" May 16 00:13:22.666490 kubelet[1910]: I0516 00:13:22.666285 1910 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" May 16 00:13:22.675307 sudo[1761]: pam_unix(sudo:session): session closed for user root May 16 00:13:22.676993 sshd[1760]: Connection closed by 10.0.0.1 port 38080 May 16 00:13:22.677437 sshd-session[1755]: pam_unix(sshd:session): session closed for user core May 16 00:13:22.681437 systemd[1]: sshd@6-10.0.0.12:22-10.0.0.1:38080.service: Deactivated successfully. May 16 00:13:22.683941 systemd-logind[1587]: Session 7 logged out. Waiting for processes to exit. May 16 00:13:22.684029 systemd[1]: session-7.scope: Deactivated successfully. May 16 00:13:22.685473 systemd-logind[1587]: Removed session 7. May 16 00:13:22.767504 kubelet[1910]: I0516 00:13:22.767337 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/faa8ff64-10fd-49f0-8707-d85eace420d3-xtables-lock\") pod \"kube-flannel-ds-vdtb8\" (UID: \"faa8ff64-10fd-49f0-8707-d85eace420d3\") " pod="kube-flannel/kube-flannel-ds-vdtb8" May 16 00:13:22.767504 kubelet[1910]: I0516 00:13:22.767413 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-j8jkj\" (UniqueName: \"kubernetes.io/projected/faa8ff64-10fd-49f0-8707-d85eace420d3-kube-api-access-j8jkj\") pod \"kube-flannel-ds-vdtb8\" (UID: \"faa8ff64-10fd-49f0-8707-d85eace420d3\") " pod="kube-flannel/kube-flannel-ds-vdtb8" May 16 00:13:22.867752 kubelet[1910]: I0516 00:13:22.867671 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/35bffc70-c677-4503-907d-cfee317c5b24-lib-modules\") pod \"kube-proxy-g9fwr\" (UID: \"35bffc70-c677-4503-907d-cfee317c5b24\") " pod="kube-system/kube-proxy-g9fwr" May 16 00:13:22.867752 kubelet[1910]: I0516 00:13:22.867749 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/35bffc70-c677-4503-907d-cfee317c5b24-kube-proxy\") pod \"kube-proxy-g9fwr\" (UID: \"35bffc70-c677-4503-907d-cfee317c5b24\") " pod="kube-system/kube-proxy-g9fwr" May 16 00:13:22.868007 kubelet[1910]: I0516 00:13:22.867790 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/35bffc70-c677-4503-907d-cfee317c5b24-xtables-lock\") pod \"kube-proxy-g9fwr\" (UID: \"35bffc70-c677-4503-907d-cfee317c5b24\") " pod="kube-system/kube-proxy-g9fwr" May 16 00:13:22.868007 kubelet[1910]: I0516 00:13:22.867841 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bcksp\" (UniqueName: \"kubernetes.io/projected/35bffc70-c677-4503-907d-cfee317c5b24-kube-api-access-bcksp\") pod \"kube-proxy-g9fwr\" (UID: \"35bffc70-c677-4503-907d-cfee317c5b24\") " pod="kube-system/kube-proxy-g9fwr" May 16 00:13:22.954877 kubelet[1910]: E0516 00:13:22.954779 1910 dns.go:153] "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" May 16 00:13:22.955742 containerd[1608]: time="2025-05-16T00:13:22.955680866Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vdtb8,Uid:faa8ff64-10fd-49f0-8707-d85eace420d3,Namespace:kube-flannel,Attempt:0,}" May 16 00:13:23.274848 kubelet[1910]: E0516 00:13:23.274815 1910 dns.go:153] "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" May 16 00:13:23.275352 containerd[1608]: time="2025-05-16T00:13:23.275316852Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-g9fwr,Uid:35bffc70-c677-4503-907d-cfee317c5b24,Namespace:kube-system,Attempt:0,}" May 16 00:13:23.638572 kubelet[1910]: E0516 00:13:23.638508 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:24.547694 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount874910259.mount: Deactivated successfully. May 16 00:13:24.639046 kubelet[1910]: E0516 00:13:24.638990 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:24.673031 containerd[1608]: time="2025-05-16T00:13:24.672965802Z" 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\"}" May 16 00:13:24.709765 containerd[1608]: time="2025-05-16T00:13:24.709688663Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" May 16 00:13:24.727327 containerd[1608]: time="2025-05-16T00:13:24.727266294Z" 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\"}" May 16 00:13:24.739672 containerd[1608]: time="2025-05-16T00:13:24.739612482Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 16 00:13:24.752406 containerd[1608]: time="2025-05-16T00:13:24.752288909Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" May 16 00:13:24.759768 containerd[1608]: time="2025-05-16T00:13:24.759702936Z" 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\"}" May 16 00:13:24.760687 containerd[1608]: time="2025-05-16T00:13:24.760632930Z" 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 1.80478408s" May 16 00:13:24.781486 containerd[1608]: time="2025-05-16T00:13:24.781416043Z" 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 1.505988674s" May 16 00:13:25.190257 containerd[1608]: time="2025-05-16T00:13:25.188465071Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:13:25.190257 containerd[1608]: time="2025-05-16T00:13:25.190224802Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:13:25.190766 containerd[1608]: time="2025-05-16T00:13:25.190470092Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:13:25.190895 containerd[1608]: time="2025-05-16T00:13:25.190724429Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:13:25.192029 containerd[1608]: time="2025-05-16T00:13:25.191946331Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:13:25.192080 containerd[1608]: time="2025-05-16T00:13:25.192021702Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:13:25.192080 containerd[1608]: time="2025-05-16T00:13:25.192037873Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:13:25.192214 containerd[1608]: time="2025-05-16T00:13:25.192186742Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:13:25.526517 containerd[1608]: time="2025-05-16T00:13:25.526375244Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-g9fwr,Uid:35bffc70-c677-4503-907d-cfee317c5b24,Namespace:kube-system,Attempt:0,} returns sandbox id \"ade6435f8ecad263dd85bcef0cc5f3abb0fa552504b62107060b2ba03181a63f\"" May 16 00:13:25.528091 kubelet[1910]: E0516 00:13:25.528066 1910 dns.go:153] "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" May 16 00:13:25.529446 containerd[1608]: time="2025-05-16T00:13:25.529410698Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.9\"" May 16 00:13:25.568172 containerd[1608]: time="2025-05-16T00:13:25.568126537Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-vdtb8,Uid:faa8ff64-10fd-49f0-8707-d85eace420d3,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"e88488b14ff6a4088daed6a88cb989e5a9f8f3a60172cba6bc9eb749682ec74c\"" May 16 00:13:25.569012 kubelet[1910]: E0516 00:13:25.568986 1910 dns.go:153] "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" May 16 00:13:25.639888 kubelet[1910]: E0516 00:13:25.639823 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:26.640466 kubelet[1910]: E0516 00:13:26.640389 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:27.641225 kubelet[1910]: E0516 00:13:27.641181 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:28.001911 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1602661676.mount: Deactivated successfully. May 16 00:13:28.641817 kubelet[1910]: E0516 00:13:28.641764 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:28.918347 containerd[1608]: time="2025-05-16T00:13:28.918211930Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:28.919381 containerd[1608]: time="2025-05-16T00:13:28.919331801Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.9: active requests=0, bytes read=30355623" May 16 00:13:28.921923 containerd[1608]: time="2025-05-16T00:13:28.921857558Z" level=info msg="ImageCreate event name:\"sha256:11a47a71ed3ecf643e15a11990daed3b656279449ba9344db0b54652c4723578\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:28.924268 containerd[1608]: time="2025-05-16T00:13:28.924225560Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:fdf026cf2434537e499e9c739d189ca8fc57101d929ac5ccd8e24f979a9738c1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:28.924926 containerd[1608]: time="2025-05-16T00:13:28.924888844Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.9\" with image id \"sha256:11a47a71ed3ecf643e15a11990daed3b656279449ba9344db0b54652c4723578\", repo tag \"registry.k8s.io/kube-proxy:v1.31.9\", repo digest \"registry.k8s.io/kube-proxy@sha256:fdf026cf2434537e499e9c739d189ca8fc57101d929ac5ccd8e24f979a9738c1\", size \"30354642\" in 3.395441868s" May 16 00:13:28.924991 containerd[1608]: time="2025-05-16T00:13:28.924924371Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.9\" returns image reference \"sha256:11a47a71ed3ecf643e15a11990daed3b656279449ba9344db0b54652c4723578\"" May 16 00:13:28.926184 containerd[1608]: time="2025-05-16T00:13:28.926151082Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 16 00:13:28.927395 containerd[1608]: time="2025-05-16T00:13:28.927356473Z" level=info msg="CreateContainer within sandbox \"ade6435f8ecad263dd85bcef0cc5f3abb0fa552504b62107060b2ba03181a63f\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 16 00:13:28.943333 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1260031305.mount: Deactivated successfully. May 16 00:13:28.947422 containerd[1608]: time="2025-05-16T00:13:28.947359252Z" level=info msg="CreateContainer within sandbox \"ade6435f8ecad263dd85bcef0cc5f3abb0fa552504b62107060b2ba03181a63f\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"c026a3239262a36c0b96090a4eb13b56e588a9dd3c2e5a2906d3e30252ac0d9d\"" May 16 00:13:28.948323 containerd[1608]: time="2025-05-16T00:13:28.948280220Z" level=info msg="StartContainer for \"c026a3239262a36c0b96090a4eb13b56e588a9dd3c2e5a2906d3e30252ac0d9d\"" May 16 00:13:29.056137 containerd[1608]: time="2025-05-16T00:13:29.056085281Z" level=info msg="StartContainer for \"c026a3239262a36c0b96090a4eb13b56e588a9dd3c2e5a2906d3e30252ac0d9d\" returns successfully" May 16 00:13:29.072767 kubelet[1910]: E0516 00:13:29.072734 1910 dns.go:153] "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" May 16 00:13:29.083657 kubelet[1910]: I0516 00:13:29.083576 1910 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-g9fwr" podStartSLOduration=3.686683376 podStartE2EDuration="7.083556068s" podCreationTimestamp="2025-05-16 00:13:22 +0000 UTC" firstStartedPulling="2025-05-16 00:13:25.528940135 +0000 UTC m=+5.104977359" lastFinishedPulling="2025-05-16 00:13:28.925812797 +0000 UTC m=+8.501850051" observedRunningTime="2025-05-16 00:13:29.083505283 +0000 UTC m=+8.659542527" watchObservedRunningTime="2025-05-16 00:13:29.083556068 +0000 UTC m=+8.659593292" May 16 00:13:29.642713 kubelet[1910]: E0516 00:13:29.642654 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:30.074507 kubelet[1910]: E0516 00:13:30.074457 1910 dns.go:153] "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" May 16 00:13:30.643658 kubelet[1910]: E0516 00:13:30.643597 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:30.704228 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount307480004.mount: Deactivated successfully. May 16 00:13:30.768391 containerd[1608]: time="2025-05-16T00:13:30.768317099Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:30.769588 containerd[1608]: time="2025-05-16T00:13:30.769549460Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852936" May 16 00:13:30.771460 containerd[1608]: time="2025-05-16T00:13:30.771381366Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:30.773919 containerd[1608]: time="2025-05-16T00:13:30.773874042Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:30.774683 containerd[1608]: time="2025-05-16T00:13:30.774628817Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 1.848435066s" May 16 00:13:30.774683 containerd[1608]: time="2025-05-16T00:13:30.774667771Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" May 16 00:13:30.777117 containerd[1608]: time="2025-05-16T00:13:30.777081969Z" level=info msg="CreateContainer within sandbox \"e88488b14ff6a4088daed6a88cb989e5a9f8f3a60172cba6bc9eb749682ec74c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 16 00:13:30.803564 containerd[1608]: time="2025-05-16T00:13:30.803495693Z" level=info msg="CreateContainer within sandbox \"e88488b14ff6a4088daed6a88cb989e5a9f8f3a60172cba6bc9eb749682ec74c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"9055bbe0fbbaf3956af69dc0a1bd3a94bc1728bd366da84142fd9f78170bc580\"" May 16 00:13:30.804158 containerd[1608]: time="2025-05-16T00:13:30.804114765Z" level=info msg="StartContainer for \"9055bbe0fbbaf3956af69dc0a1bd3a94bc1728bd366da84142fd9f78170bc580\"" May 16 00:13:30.880860 containerd[1608]: time="2025-05-16T00:13:30.880818820Z" level=info msg="StartContainer for \"9055bbe0fbbaf3956af69dc0a1bd3a94bc1728bd366da84142fd9f78170bc580\" returns successfully" May 16 00:13:31.077202 kubelet[1910]: E0516 00:13:31.077170 1910 dns.go:153] "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" May 16 00:13:31.547484 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9055bbe0fbbaf3956af69dc0a1bd3a94bc1728bd366da84142fd9f78170bc580-rootfs.mount: Deactivated successfully. May 16 00:13:31.644717 kubelet[1910]: E0516 00:13:31.644643 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:31.771642 containerd[1608]: time="2025-05-16T00:13:31.771571026Z" level=info msg="shim disconnected" id=9055bbe0fbbaf3956af69dc0a1bd3a94bc1728bd366da84142fd9f78170bc580 namespace=k8s.io May 16 00:13:31.771642 containerd[1608]: time="2025-05-16T00:13:31.771635247Z" level=warning msg="cleaning up after shim disconnected" id=9055bbe0fbbaf3956af69dc0a1bd3a94bc1728bd366da84142fd9f78170bc580 namespace=k8s.io May 16 00:13:31.771642 containerd[1608]: time="2025-05-16T00:13:31.771645716Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 16 00:13:32.079114 kubelet[1910]: E0516 00:13:32.079071 1910 dns.go:153] "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" May 16 00:13:32.079911 containerd[1608]: time="2025-05-16T00:13:32.079871109Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 16 00:13:32.644880 kubelet[1910]: E0516 00:13:32.644783 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:33.645441 kubelet[1910]: E0516 00:13:33.645390 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:34.646122 kubelet[1910]: E0516 00:13:34.646039 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:34.894380 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1559881487.mount: Deactivated successfully. May 16 00:13:35.646676 kubelet[1910]: E0516 00:13:35.646633 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:36.647304 kubelet[1910]: E0516 00:13:36.647246 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:36.764643 containerd[1608]: time="2025-05-16T00:13:36.764581236Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:36.766225 containerd[1608]: time="2025-05-16T00:13:36.765981973Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" May 16 00:13:36.769594 containerd[1608]: time="2025-05-16T00:13:36.769538274Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:36.773369 containerd[1608]: time="2025-05-16T00:13:36.773323554Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:13:36.774590 containerd[1608]: time="2025-05-16T00:13:36.774557769Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 4.694645443s" May 16 00:13:36.774648 containerd[1608]: time="2025-05-16T00:13:36.774588737Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" May 16 00:13:36.777355 containerd[1608]: time="2025-05-16T00:13:36.777313037Z" level=info msg="CreateContainer within sandbox \"e88488b14ff6a4088daed6a88cb989e5a9f8f3a60172cba6bc9eb749682ec74c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 16 00:13:36.795332 containerd[1608]: time="2025-05-16T00:13:36.795291019Z" level=info msg="CreateContainer within sandbox \"e88488b14ff6a4088daed6a88cb989e5a9f8f3a60172cba6bc9eb749682ec74c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"c3be79175faf4897dad47ae22303d8fd4e0693552c788b7894a8842e9ff7a46c\"" May 16 00:13:36.795902 containerd[1608]: time="2025-05-16T00:13:36.795872389Z" level=info msg="StartContainer for \"c3be79175faf4897dad47ae22303d8fd4e0693552c788b7894a8842e9ff7a46c\"" May 16 00:13:36.854404 containerd[1608]: time="2025-05-16T00:13:36.854365345Z" level=info msg="StartContainer for \"c3be79175faf4897dad47ae22303d8fd4e0693552c788b7894a8842e9ff7a46c\" returns successfully" May 16 00:13:36.869844 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c3be79175faf4897dad47ae22303d8fd4e0693552c788b7894a8842e9ff7a46c-rootfs.mount: Deactivated successfully. May 16 00:13:36.932202 kubelet[1910]: I0516 00:13:36.924897 1910 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 16 00:13:37.088043 kubelet[1910]: E0516 00:13:37.088013 1910 dns.go:153] "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" May 16 00:13:37.233733 containerd[1608]: time="2025-05-16T00:13:37.233571969Z" level=info msg="shim disconnected" id=c3be79175faf4897dad47ae22303d8fd4e0693552c788b7894a8842e9ff7a46c namespace=k8s.io May 16 00:13:37.233733 containerd[1608]: time="2025-05-16T00:13:37.233653462Z" level=warning msg="cleaning up after shim disconnected" id=c3be79175faf4897dad47ae22303d8fd4e0693552c788b7894a8842e9ff7a46c namespace=k8s.io May 16 00:13:37.233733 containerd[1608]: time="2025-05-16T00:13:37.233666706Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 16 00:13:37.647566 kubelet[1910]: E0516 00:13:37.647501 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:38.091263 kubelet[1910]: E0516 00:13:38.091229 1910 dns.go:153] "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" May 16 00:13:38.092789 containerd[1608]: time="2025-05-16T00:13:38.092741124Z" level=info msg="CreateContainer within sandbox \"e88488b14ff6a4088daed6a88cb989e5a9f8f3a60172cba6bc9eb749682ec74c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 16 00:13:38.510937 containerd[1608]: time="2025-05-16T00:13:38.510772802Z" level=info msg="CreateContainer within sandbox \"e88488b14ff6a4088daed6a88cb989e5a9f8f3a60172cba6bc9eb749682ec74c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"05852a9cb218c9a39b9e2f242b366a97da34f6cbd9215e0b971fd0d6b8e37f14\"" May 16 00:13:38.511324 containerd[1608]: time="2025-05-16T00:13:38.511299701Z" level=info msg="StartContainer for \"05852a9cb218c9a39b9e2f242b366a97da34f6cbd9215e0b971fd0d6b8e37f14\"" May 16 00:13:38.648531 kubelet[1910]: E0516 00:13:38.648468 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:38.655334 containerd[1608]: time="2025-05-16T00:13:38.655279385Z" level=info msg="StartContainer for \"05852a9cb218c9a39b9e2f242b366a97da34f6cbd9215e0b971fd0d6b8e37f14\" returns successfully" May 16 00:13:39.095425 kubelet[1910]: E0516 00:13:39.095381 1910 dns.go:153] "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" May 16 00:13:39.238247 kubelet[1910]: I0516 00:13:39.238146 1910 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-vdtb8" podStartSLOduration=6.031708539 podStartE2EDuration="17.238126005s" podCreationTimestamp="2025-05-16 00:13:22 +0000 UTC" firstStartedPulling="2025-05-16 00:13:25.569432086 +0000 UTC m=+5.145469310" lastFinishedPulling="2025-05-16 00:13:36.775849552 +0000 UTC m=+16.351886776" observedRunningTime="2025-05-16 00:13:39.237881413 +0000 UTC m=+18.813918667" watchObservedRunningTime="2025-05-16 00:13:39.238126005 +0000 UTC m=+18.814163229" May 16 00:13:39.314141 kubelet[1910]: I0516 00:13:39.314080 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qhn2m\" (UniqueName: \"kubernetes.io/projected/a69b163d-b1d4-46a1-87bc-054e33d4c1d3-kube-api-access-qhn2m\") pod \"nginx-deployment-8587fbcb89-42mvd\" (UID: \"a69b163d-b1d4-46a1-87bc-054e33d4c1d3\") " pod="default/nginx-deployment-8587fbcb89-42mvd" May 16 00:13:39.442776 containerd[1608]: time="2025-05-16T00:13:39.442655358Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-42mvd,Uid:a69b163d-b1d4-46a1-87bc-054e33d4c1d3,Namespace:default,Attempt:0,}" May 16 00:13:39.489162 systemd[1]: run-netns-cni\x2da28dcd3a\x2d4e6b\x2d68c1\x2d7f5e\x2d32112c6f4e2e.mount: Deactivated successfully. May 16 00:13:39.489375 systemd[1]: run-containerd-io.containerd.grpc.v1.cri-sandboxes-47ec58a32a1a103e05ed7433658a0309606945a79b7494ad241a42b790bd0762-shm.mount: Deactivated successfully. May 16 00:13:39.490714 containerd[1608]: time="2025-05-16T00:13:39.490519944Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-42mvd,Uid:a69b163d-b1d4-46a1-87bc-054e33d4c1d3,Namespace:default,Attempt:0,} failed, error" error="failed to setup network for sandbox \"47ec58a32a1a103e05ed7433658a0309606945a79b7494ad241a42b790bd0762\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" May 16 00:13:39.490959 kubelet[1910]: E0516 00:13:39.490909 1910 log.go:32] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"47ec58a32a1a103e05ed7433658a0309606945a79b7494ad241a42b790bd0762\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" May 16 00:13:39.491042 kubelet[1910]: E0516 00:13:39.490996 1910 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"47ec58a32a1a103e05ed7433658a0309606945a79b7494ad241a42b790bd0762\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-8587fbcb89-42mvd" May 16 00:13:39.491042 kubelet[1910]: E0516 00:13:39.491023 1910 kuberuntime_manager.go:1170] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"47ec58a32a1a103e05ed7433658a0309606945a79b7494ad241a42b790bd0762\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-8587fbcb89-42mvd" May 16 00:13:39.491135 kubelet[1910]: E0516 00:13:39.491106 1910 pod_workers.go:1301] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-8587fbcb89-42mvd_default(a69b163d-b1d4-46a1-87bc-054e33d4c1d3)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-8587fbcb89-42mvd_default(a69b163d-b1d4-46a1-87bc-054e33d4c1d3)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"47ec58a32a1a103e05ed7433658a0309606945a79b7494ad241a42b790bd0762\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-8587fbcb89-42mvd" podUID="a69b163d-b1d4-46a1-87bc-054e33d4c1d3" May 16 00:13:39.648910 systemd-networkd[1245]: flannel.1: Link UP May 16 00:13:39.648925 systemd-networkd[1245]: flannel.1: Gained carrier May 16 00:13:39.650450 kubelet[1910]: E0516 00:13:39.650405 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:40.097259 kubelet[1910]: E0516 00:13:40.097214 1910 dns.go:153] "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" May 16 00:13:40.637384 kubelet[1910]: E0516 00:13:40.637314 1910 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:40.653210 kubelet[1910]: E0516 00:13:40.653159 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:41.551954 systemd-networkd[1245]: flannel.1: Gained IPv6LL May 16 00:13:41.654377 kubelet[1910]: E0516 00:13:41.654303 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:42.654912 kubelet[1910]: E0516 00:13:42.654845 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:43.656037 kubelet[1910]: E0516 00:13:43.655961 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:44.656952 kubelet[1910]: E0516 00:13:44.656893 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:45.657866 kubelet[1910]: E0516 00:13:45.657814 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:46.659026 kubelet[1910]: E0516 00:13:46.658951 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:47.659880 kubelet[1910]: E0516 00:13:47.659821 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:48.660780 kubelet[1910]: E0516 00:13:48.660713 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:49.661885 kubelet[1910]: E0516 00:13:49.661814 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:50.662886 kubelet[1910]: E0516 00:13:50.662829 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:51.605712 update_engine[1590]: I20250516 00:13:51.605578 1590 update_attempter.cc:509] Updating boot flags... May 16 00:13:51.663594 kubelet[1910]: E0516 00:13:51.663554 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:51.669895 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2567) May 16 00:13:51.711875 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2566) May 16 00:13:51.727866 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2566) May 16 00:13:52.664164 kubelet[1910]: E0516 00:13:52.664110 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:53.057236 containerd[1608]: time="2025-05-16T00:13:53.057203218Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-42mvd,Uid:a69b163d-b1d4-46a1-87bc-054e33d4c1d3,Namespace:default,Attempt:0,}" May 16 00:13:53.289218 systemd-networkd[1245]: cni0: Link UP May 16 00:13:53.289226 systemd-networkd[1245]: cni0: Gained carrier May 16 00:13:53.289580 systemd-networkd[1245]: cni0: Lost carrier May 16 00:13:53.332112 kernel: cni0: port 1(vethb2cdc6e0) entered blocking state May 16 00:13:53.332206 kernel: cni0: port 1(vethb2cdc6e0) entered disabled state May 16 00:13:53.332221 kernel: vethb2cdc6e0: entered allmulticast mode May 16 00:13:53.333840 kernel: vethb2cdc6e0: entered promiscuous mode May 16 00:13:53.335447 kernel: cni0: port 1(vethb2cdc6e0) entered blocking state May 16 00:13:53.335482 kernel: cni0: port 1(vethb2cdc6e0) entered forwarding state May 16 00:13:53.336443 kernel: cni0: port 1(vethb2cdc6e0) entered disabled state May 16 00:13:53.336564 systemd-networkd[1245]: vethb2cdc6e0: Link UP May 16 00:13:53.350421 kernel: cni0: port 1(vethb2cdc6e0) entered blocking state May 16 00:13:53.351490 kernel: cni0: port 1(vethb2cdc6e0) entered forwarding state May 16 00:13:53.350514 systemd-networkd[1245]: vethb2cdc6e0: Gained carrier May 16 00:13:53.350881 systemd-networkd[1245]: cni0: Gained carrier May 16 00:13:53.352983 containerd[1608]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001c938), "name":"cbr0", "type":"bridge"} May 16 00:13:53.352983 containerd[1608]: delegateAdd: netconf sent to delegate plugin: May 16 00:13:53.381609 containerd[1608]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-16T00:13:53.381499457Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:13:53.381609 containerd[1608]: time="2025-05-16T00:13:53.381572004Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:13:53.381609 containerd[1608]: time="2025-05-16T00:13:53.381585290Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:13:53.381830 containerd[1608]: time="2025-05-16T00:13:53.381675351Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:13:53.398700 systemd[1]: run-containerd-runc-k8s.io-87c6baa237bdbf2fd0e0cbe2ef1f96203864a651242d8a4812fd09ec556e9874-runc.xLiFeO.mount: Deactivated successfully. May 16 00:13:53.405993 systemd-resolved[1461]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 16 00:13:53.430665 containerd[1608]: time="2025-05-16T00:13:53.430623941Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-42mvd,Uid:a69b163d-b1d4-46a1-87bc-054e33d4c1d3,Namespace:default,Attempt:0,} returns sandbox id \"87c6baa237bdbf2fd0e0cbe2ef1f96203864a651242d8a4812fd09ec556e9874\"" May 16 00:13:53.432000 containerd[1608]: time="2025-05-16T00:13:53.431927695Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 16 00:13:53.665463 kubelet[1910]: E0516 00:13:53.665284 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:54.608091 systemd-networkd[1245]: cni0: Gained IPv6LL May 16 00:13:54.665706 kubelet[1910]: E0516 00:13:54.665628 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:55.120918 systemd-networkd[1245]: vethb2cdc6e0: Gained IPv6LL May 16 00:13:55.666123 kubelet[1910]: E0516 00:13:55.666080 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:56.667151 kubelet[1910]: E0516 00:13:56.667093 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:57.492334 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2685165678.mount: Deactivated successfully. May 16 00:13:57.667918 kubelet[1910]: E0516 00:13:57.667867 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:58.668950 kubelet[1910]: E0516 00:13:58.668894 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:13:59.669822 kubelet[1910]: E0516 00:13:59.669752 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:00.592409 containerd[1608]: time="2025-05-16T00:14:00.592314421Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:14:00.637931 kubelet[1910]: E0516 00:14:00.637861 1910 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:00.670397 kubelet[1910]: E0516 00:14:00.670348 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:00.671403 containerd[1608]: time="2025-05-16T00:14:00.671342602Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73306220" May 16 00:14:00.701331 containerd[1608]: time="2025-05-16T00:14:00.701238982Z" level=info msg="ImageCreate event name:\"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:14:00.758566 containerd[1608]: time="2025-05-16T00:14:00.758492802Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:14:00.760002 containerd[1608]: time="2025-05-16T00:14:00.759942022Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 7.327976633s" May 16 00:14:00.760053 containerd[1608]: time="2025-05-16T00:14:00.760009389Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 16 00:14:00.766964 containerd[1608]: time="2025-05-16T00:14:00.766916643Z" level=info msg="CreateContainer within sandbox \"87c6baa237bdbf2fd0e0cbe2ef1f96203864a651242d8a4812fd09ec556e9874\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 16 00:14:00.963975 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4026886858.mount: Deactivated successfully. May 16 00:14:01.010333 containerd[1608]: time="2025-05-16T00:14:01.010214990Z" level=info msg="CreateContainer within sandbox \"87c6baa237bdbf2fd0e0cbe2ef1f96203864a651242d8a4812fd09ec556e9874\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"0a27d65fd90446a9dd683f14117e1ef911178814308eecf9cc44c00ef072c233\"" May 16 00:14:01.010954 containerd[1608]: time="2025-05-16T00:14:01.010905293Z" level=info msg="StartContainer for \"0a27d65fd90446a9dd683f14117e1ef911178814308eecf9cc44c00ef072c233\"" May 16 00:14:01.138789 containerd[1608]: time="2025-05-16T00:14:01.138724511Z" level=info msg="StartContainer for \"0a27d65fd90446a9dd683f14117e1ef911178814308eecf9cc44c00ef072c233\" returns successfully" May 16 00:14:01.670623 kubelet[1910]: E0516 00:14:01.670540 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:02.176460 kubelet[1910]: I0516 00:14:02.176390 1910 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-42mvd" podStartSLOduration=15.842447859 podStartE2EDuration="23.176373398s" podCreationTimestamp="2025-05-16 00:13:39 +0000 UTC" firstStartedPulling="2025-05-16 00:13:53.43168736 +0000 UTC m=+33.007724584" lastFinishedPulling="2025-05-16 00:14:00.765612899 +0000 UTC m=+40.341650123" observedRunningTime="2025-05-16 00:14:02.176123075 +0000 UTC m=+41.752160309" watchObservedRunningTime="2025-05-16 00:14:02.176373398 +0000 UTC m=+41.752410622" May 16 00:14:02.671592 kubelet[1910]: E0516 00:14:02.671524 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:03.672577 kubelet[1910]: E0516 00:14:03.672510 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:04.673587 kubelet[1910]: E0516 00:14:04.673525 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:05.673920 kubelet[1910]: E0516 00:14:05.673862 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:06.674968 kubelet[1910]: E0516 00:14:06.674885 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:07.676036 kubelet[1910]: E0516 00:14:07.675933 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:07.746454 kubelet[1910]: I0516 00:14:07.746383 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/a358971d-a58c-4d73-9bdc-af7332068294-data\") pod \"nfs-server-provisioner-0\" (UID: \"a358971d-a58c-4d73-9bdc-af7332068294\") " pod="default/nfs-server-provisioner-0" May 16 00:14:07.746454 kubelet[1910]: I0516 00:14:07.746445 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4bj57\" (UniqueName: \"kubernetes.io/projected/a358971d-a58c-4d73-9bdc-af7332068294-kube-api-access-4bj57\") pod \"nfs-server-provisioner-0\" (UID: \"a358971d-a58c-4d73-9bdc-af7332068294\") " pod="default/nfs-server-provisioner-0" May 16 00:14:07.883177 containerd[1608]: time="2025-05-16T00:14:07.883121511Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a358971d-a58c-4d73-9bdc-af7332068294,Namespace:default,Attempt:0,}" May 16 00:14:07.906062 systemd-networkd[1245]: vethcc6f5cf7: Link UP May 16 00:14:07.907951 kernel: cni0: port 2(vethcc6f5cf7) entered blocking state May 16 00:14:07.908051 kernel: cni0: port 2(vethcc6f5cf7) entered disabled state May 16 00:14:07.908072 kernel: vethcc6f5cf7: entered allmulticast mode May 16 00:14:07.909250 kernel: vethcc6f5cf7: entered promiscuous mode May 16 00:14:07.914843 kernel: cni0: port 2(vethcc6f5cf7) entered blocking state May 16 00:14:07.914942 kernel: cni0: port 2(vethcc6f5cf7) entered forwarding state May 16 00:14:07.914855 systemd-networkd[1245]: vethcc6f5cf7: Gained carrier May 16 00:14:07.920458 containerd[1608]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00011c8e8), "name":"cbr0", "type":"bridge"} May 16 00:14:07.920458 containerd[1608]: delegateAdd: netconf sent to delegate plugin: May 16 00:14:07.945792 containerd[1608]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-16T00:14:07.945010855Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:14:07.945792 containerd[1608]: time="2025-05-16T00:14:07.945671600Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:14:07.945792 containerd[1608]: time="2025-05-16T00:14:07.945726092Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:14:07.946061 containerd[1608]: time="2025-05-16T00:14:07.945950886Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:14:07.974882 systemd-resolved[1461]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 16 00:14:08.003105 containerd[1608]: time="2025-05-16T00:14:08.003059909Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a358971d-a58c-4d73-9bdc-af7332068294,Namespace:default,Attempt:0,} returns sandbox id \"84b33637d1c58e9c4ded519fcf079f5a245be413cd5c8beaea8420490fea96cc\"" May 16 00:14:08.004620 containerd[1608]: time="2025-05-16T00:14:08.004596665Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 16 00:14:08.676882 kubelet[1910]: E0516 00:14:08.676817 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:09.392032 systemd-networkd[1245]: vethcc6f5cf7: Gained IPv6LL May 16 00:14:09.678112 kubelet[1910]: E0516 00:14:09.677934 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:10.269254 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3628274152.mount: Deactivated successfully. May 16 00:14:10.678952 kubelet[1910]: E0516 00:14:10.678883 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:11.680185 kubelet[1910]: E0516 00:14:11.680132 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:12.681137 kubelet[1910]: E0516 00:14:12.681088 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:13.685889 kubelet[1910]: E0516 00:14:13.685772 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:14.689315 kubelet[1910]: E0516 00:14:14.687404 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:14.813398 containerd[1608]: time="2025-05-16T00:14:14.813305484Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:14:14.823137 containerd[1608]: time="2025-05-16T00:14:14.820462089Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" May 16 00:14:14.823467 containerd[1608]: time="2025-05-16T00:14:14.823404244Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:14:14.845276 containerd[1608]: time="2025-05-16T00:14:14.842676603Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:14:14.845276 containerd[1608]: time="2025-05-16T00:14:14.844164032Z" 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 6.83953134s" May 16 00:14:14.845276 containerd[1608]: time="2025-05-16T00:14:14.844202354Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" May 16 00:14:14.872194 containerd[1608]: time="2025-05-16T00:14:14.871607686Z" level=info msg="CreateContainer within sandbox \"84b33637d1c58e9c4ded519fcf079f5a245be413cd5c8beaea8420490fea96cc\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 16 00:14:14.944546 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount172046388.mount: Deactivated successfully. May 16 00:14:14.960380 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3036053287.mount: Deactivated successfully. May 16 00:14:14.994637 containerd[1608]: time="2025-05-16T00:14:14.994540036Z" level=info msg="CreateContainer within sandbox \"84b33637d1c58e9c4ded519fcf079f5a245be413cd5c8beaea8420490fea96cc\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"00bda6e4e4f7f8b5fb01631e2e1466037de3df2b1861b82c68b34a642c82dc80\"" May 16 00:14:14.996365 containerd[1608]: time="2025-05-16T00:14:14.995699518Z" level=info msg="StartContainer for \"00bda6e4e4f7f8b5fb01631e2e1466037de3df2b1861b82c68b34a642c82dc80\"" May 16 00:14:15.125327 containerd[1608]: time="2025-05-16T00:14:15.125250506Z" level=info msg="StartContainer for \"00bda6e4e4f7f8b5fb01631e2e1466037de3df2b1861b82c68b34a642c82dc80\" returns successfully" May 16 00:14:15.690283 kubelet[1910]: E0516 00:14:15.690130 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:16.690827 kubelet[1910]: E0516 00:14:16.690735 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:17.691676 kubelet[1910]: E0516 00:14:17.691540 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:18.692220 kubelet[1910]: E0516 00:14:18.692153 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:19.693276 kubelet[1910]: E0516 00:14:19.693209 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:20.637627 kubelet[1910]: E0516 00:14:20.637525 1910 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:20.693770 kubelet[1910]: E0516 00:14:20.693715 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:21.694780 kubelet[1910]: E0516 00:14:21.694693 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:22.695268 kubelet[1910]: E0516 00:14:22.695196 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:23.695989 kubelet[1910]: E0516 00:14:23.695893 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:24.697021 kubelet[1910]: E0516 00:14:24.696956 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:25.412531 kubelet[1910]: I0516 00:14:25.412433 1910 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.562560723 podStartE2EDuration="18.412410908s" podCreationTimestamp="2025-05-16 00:14:07 +0000 UTC" firstStartedPulling="2025-05-16 00:14:08.004344339 +0000 UTC m=+47.580381563" lastFinishedPulling="2025-05-16 00:14:14.854194524 +0000 UTC m=+54.430231748" observedRunningTime="2025-05-16 00:14:15.269660024 +0000 UTC m=+54.845697268" watchObservedRunningTime="2025-05-16 00:14:25.412410908 +0000 UTC m=+64.988448162" May 16 00:14:25.525169 kubelet[1910]: I0516 00:14:25.525115 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gsfvv\" (UniqueName: \"kubernetes.io/projected/9e5c082e-146f-439a-a7fa-9ce15db359ba-kube-api-access-gsfvv\") pod \"test-pod-1\" (UID: \"9e5c082e-146f-439a-a7fa-9ce15db359ba\") " pod="default/test-pod-1" May 16 00:14:25.525338 kubelet[1910]: I0516 00:14:25.525200 1910 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-3d5f5f08-5b3e-48f4-a643-e25fca7411fa\" (UniqueName: \"kubernetes.io/nfs/9e5c082e-146f-439a-a7fa-9ce15db359ba-pvc-3d5f5f08-5b3e-48f4-a643-e25fca7411fa\") pod \"test-pod-1\" (UID: \"9e5c082e-146f-439a-a7fa-9ce15db359ba\") " pod="default/test-pod-1" May 16 00:14:25.651909 kernel: FS-Cache: Loaded May 16 00:14:25.697764 kubelet[1910]: E0516 00:14:25.697571 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:25.722248 kernel: RPC: Registered named UNIX socket transport module. May 16 00:14:25.722395 kernel: RPC: Registered udp transport module. May 16 00:14:25.722419 kernel: RPC: Registered tcp transport module. May 16 00:14:25.722897 kernel: RPC: Registered tcp-with-tls transport module. May 16 00:14:25.724428 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 16 00:14:26.005375 kernel: NFS: Registering the id_resolver key type May 16 00:14:26.005524 kernel: Key type id_resolver registered May 16 00:14:26.005568 kernel: Key type id_legacy registered May 16 00:14:26.051371 nfsidmap[3100]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 16 00:14:26.055600 nfsidmap[3103]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 16 00:14:26.317590 containerd[1608]: time="2025-05-16T00:14:26.317544343Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9e5c082e-146f-439a-a7fa-9ce15db359ba,Namespace:default,Attempt:0,}" May 16 00:14:26.549667 systemd-networkd[1245]: vethb42ad9fe: Link UP May 16 00:14:26.552353 kernel: cni0: port 3(vethb42ad9fe) entered blocking state May 16 00:14:26.552401 kernel: cni0: port 3(vethb42ad9fe) entered disabled state May 16 00:14:26.552421 kernel: vethb42ad9fe: entered allmulticast mode May 16 00:14:26.554056 kernel: vethb42ad9fe: entered promiscuous mode May 16 00:14:26.561453 kernel: cni0: port 3(vethb42ad9fe) entered blocking state May 16 00:14:26.561540 kernel: cni0: port 3(vethb42ad9fe) entered forwarding state May 16 00:14:26.561906 systemd-networkd[1245]: vethb42ad9fe: Gained carrier May 16 00:14:26.563286 containerd[1608]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000020938), "name":"cbr0", "type":"bridge"} May 16 00:14:26.563286 containerd[1608]: delegateAdd: netconf sent to delegate plugin: May 16 00:14:26.587959 containerd[1608]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-05-16T00:14:26.587482684Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 16 00:14:26.587959 containerd[1608]: time="2025-05-16T00:14:26.587539200Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 16 00:14:26.587959 containerd[1608]: time="2025-05-16T00:14:26.587558506Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:14:26.587959 containerd[1608]: time="2025-05-16T00:14:26.587695102Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 16 00:14:26.615761 systemd-resolved[1461]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 16 00:14:26.642199 containerd[1608]: time="2025-05-16T00:14:26.642164054Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:9e5c082e-146f-439a-a7fa-9ce15db359ba,Namespace:default,Attempt:0,} returns sandbox id \"f88d1e89c7edf7264a4b5fa60a472f182de861aab8436520d055008746042fde\"" May 16 00:14:26.643524 containerd[1608]: time="2025-05-16T00:14:26.643480237Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 16 00:14:26.698449 kubelet[1910]: E0516 00:14:26.698385 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:27.022108 containerd[1608]: time="2025-05-16T00:14:27.021934816Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 16 00:14:27.024789 containerd[1608]: time="2025-05-16T00:14:27.024726058Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 16 00:14:27.032208 containerd[1608]: time="2025-05-16T00:14:27.032148398Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 388.625501ms" May 16 00:14:27.032208 containerd[1608]: time="2025-05-16T00:14:27.032206788Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 16 00:14:27.034632 containerd[1608]: time="2025-05-16T00:14:27.034606575Z" level=info msg="CreateContainer within sandbox \"f88d1e89c7edf7264a4b5fa60a472f182de861aab8436520d055008746042fde\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 16 00:14:27.054192 containerd[1608]: time="2025-05-16T00:14:27.054146526Z" level=info msg="CreateContainer within sandbox \"f88d1e89c7edf7264a4b5fa60a472f182de861aab8436520d055008746042fde\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"bcf0aab73ab7982eb1316905fa138ce12a06635ab59eab487d3b49e502b7d5df\"" May 16 00:14:27.054856 containerd[1608]: time="2025-05-16T00:14:27.054767151Z" level=info msg="StartContainer for \"bcf0aab73ab7982eb1316905fa138ce12a06635ab59eab487d3b49e502b7d5df\"" May 16 00:14:27.111474 containerd[1608]: time="2025-05-16T00:14:27.111431087Z" level=info msg="StartContainer for \"bcf0aab73ab7982eb1316905fa138ce12a06635ab59eab487d3b49e502b7d5df\" returns successfully" May 16 00:14:27.223944 kubelet[1910]: I0516 00:14:27.223890 1910 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=19.834009125 podStartE2EDuration="20.22387173s" podCreationTimestamp="2025-05-16 00:14:07 +0000 UTC" firstStartedPulling="2025-05-16 00:14:26.643281403 +0000 UTC m=+66.219318627" lastFinishedPulling="2025-05-16 00:14:27.033144008 +0000 UTC m=+66.609181232" observedRunningTime="2025-05-16 00:14:27.22371201 +0000 UTC m=+66.799749234" watchObservedRunningTime="2025-05-16 00:14:27.22387173 +0000 UTC m=+66.799908954" May 16 00:14:27.699442 kubelet[1910]: E0516 00:14:27.699338 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:28.400083 systemd-networkd[1245]: vethb42ad9fe: Gained IPv6LL May 16 00:14:28.700431 kubelet[1910]: E0516 00:14:28.700243 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:29.700744 kubelet[1910]: E0516 00:14:29.700674 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 16 00:14:30.701530 kubelet[1910]: E0516 00:14:30.701449 1910 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"