Nov 3 16:20:52.419322 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT_DYNAMIC Mon Nov 3 14:29:33 -00 2025 Nov 3 16:20:52.419375 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=e9d538d1fb909beabac60fa40d47676de06d795b9bab2159a3819b90e410c77a Nov 3 16:20:52.419390 kernel: BIOS-provided physical RAM map: Nov 3 16:20:52.419397 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Nov 3 16:20:52.419405 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Nov 3 16:20:52.419412 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Nov 3 16:20:52.419420 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Nov 3 16:20:52.419427 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Nov 3 16:20:52.419437 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Nov 3 16:20:52.419444 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Nov 3 16:20:52.419454 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 3 16:20:52.419461 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Nov 3 16:20:52.419468 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Nov 3 16:20:52.419475 kernel: NX (Execute Disable) protection: active Nov 3 16:20:52.419484 kernel: APIC: Static calls initialized Nov 3 16:20:52.419493 kernel: SMBIOS 2.8 present. Nov 3 16:20:52.419504 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Nov 3 16:20:52.419512 kernel: DMI: Memory slots populated: 1/1 Nov 3 16:20:52.419519 kernel: Hypervisor detected: KVM Nov 3 16:20:52.419527 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Nov 3 16:20:52.419534 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 3 16:20:52.419542 kernel: kvm-clock: using sched offset of 4460006523 cycles Nov 3 16:20:52.419551 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 3 16:20:52.419559 kernel: tsc: Detected 2794.750 MHz processor Nov 3 16:20:52.419570 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 3 16:20:52.419579 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 3 16:20:52.419587 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Nov 3 16:20:52.419595 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Nov 3 16:20:52.419603 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 3 16:20:52.419611 kernel: Using GB pages for direct mapping Nov 3 16:20:52.419619 kernel: ACPI: Early table checksum verification disabled Nov 3 16:20:52.419630 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Nov 3 16:20:52.419647 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 16:20:52.419656 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 16:20:52.419664 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 16:20:52.419672 kernel: ACPI: FACS 0x000000009CFE0000 000040 Nov 3 16:20:52.419682 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 16:20:52.419690 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 16:20:52.419700 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 16:20:52.419708 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 16:20:52.419719 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Nov 3 16:20:52.419728 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Nov 3 16:20:52.419736 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Nov 3 16:20:52.419747 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Nov 3 16:20:52.419755 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Nov 3 16:20:52.419763 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Nov 3 16:20:52.419771 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Nov 3 16:20:52.419779 kernel: No NUMA configuration found Nov 3 16:20:52.419788 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Nov 3 16:20:52.419796 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff] Nov 3 16:20:52.419807 kernel: Zone ranges: Nov 3 16:20:52.419815 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 3 16:20:52.419823 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Nov 3 16:20:52.419832 kernel: Normal empty Nov 3 16:20:52.419840 kernel: Device empty Nov 3 16:20:52.419848 kernel: Movable zone start for each node Nov 3 16:20:52.419856 kernel: Early memory node ranges Nov 3 16:20:52.419864 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Nov 3 16:20:52.419891 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Nov 3 16:20:52.419899 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Nov 3 16:20:52.419907 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 3 16:20:52.419918 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Nov 3 16:20:52.419927 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Nov 3 16:20:52.419937 kernel: ACPI: PM-Timer IO Port: 0x608 Nov 3 16:20:52.419954 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 3 16:20:52.419968 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 3 16:20:52.419977 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 3 16:20:52.419987 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 3 16:20:52.419996 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 3 16:20:52.420004 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 3 16:20:52.420012 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 3 16:20:52.420021 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 3 16:20:52.420032 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Nov 3 16:20:52.420040 kernel: TSC deadline timer available Nov 3 16:20:52.420048 kernel: CPU topo: Max. logical packages: 1 Nov 3 16:20:52.420056 kernel: CPU topo: Max. logical dies: 1 Nov 3 16:20:52.420065 kernel: CPU topo: Max. dies per package: 1 Nov 3 16:20:52.420073 kernel: CPU topo: Max. threads per core: 1 Nov 3 16:20:52.420081 kernel: CPU topo: Num. cores per package: 4 Nov 3 16:20:52.420089 kernel: CPU topo: Num. threads per package: 4 Nov 3 16:20:52.420100 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Nov 3 16:20:52.420108 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Nov 3 16:20:52.420117 kernel: kvm-guest: KVM setup pv remote TLB flush Nov 3 16:20:52.420125 kernel: kvm-guest: setup PV sched yield Nov 3 16:20:52.420133 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Nov 3 16:20:52.420141 kernel: Booting paravirtualized kernel on KVM Nov 3 16:20:52.420150 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 3 16:20:52.420161 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Nov 3 16:20:52.420169 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Nov 3 16:20:52.420177 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Nov 3 16:20:52.420186 kernel: pcpu-alloc: [0] 0 1 2 3 Nov 3 16:20:52.420194 kernel: kvm-guest: PV spinlocks enabled Nov 3 16:20:52.420202 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 3 16:20:52.420212 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=e9d538d1fb909beabac60fa40d47676de06d795b9bab2159a3819b90e410c77a Nov 3 16:20:52.420223 kernel: random: crng init done Nov 3 16:20:52.420231 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 3 16:20:52.420240 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 3 16:20:52.420248 kernel: Fallback order for Node 0: 0 Nov 3 16:20:52.420257 kernel: Built 1 zonelists, mobility grouping on. Total pages: 642938 Nov 3 16:20:52.420265 kernel: Policy zone: DMA32 Nov 3 16:20:52.420273 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 3 16:20:52.420284 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 3 16:20:52.420292 kernel: ftrace: allocating 40092 entries in 157 pages Nov 3 16:20:52.420300 kernel: ftrace: allocated 157 pages with 5 groups Nov 3 16:20:52.420309 kernel: Dynamic Preempt: voluntary Nov 3 16:20:52.420317 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 3 16:20:52.420327 kernel: rcu: RCU event tracing is enabled. Nov 3 16:20:52.420335 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 3 16:20:52.420346 kernel: Trampoline variant of Tasks RCU enabled. Nov 3 16:20:52.420356 kernel: Rude variant of Tasks RCU enabled. Nov 3 16:20:52.420365 kernel: Tracing variant of Tasks RCU enabled. Nov 3 16:20:52.420373 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 3 16:20:52.420382 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 3 16:20:52.420390 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 3 16:20:52.420398 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 3 16:20:52.420407 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Nov 3 16:20:52.420417 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Nov 3 16:20:52.420426 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 3 16:20:52.420441 kernel: Console: colour VGA+ 80x25 Nov 3 16:20:52.420452 kernel: printk: legacy console [ttyS0] enabled Nov 3 16:20:52.420461 kernel: ACPI: Core revision 20240827 Nov 3 16:20:52.420469 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Nov 3 16:20:52.420478 kernel: APIC: Switch to symmetric I/O mode setup Nov 3 16:20:52.420486 kernel: x2apic enabled Nov 3 16:20:52.420495 kernel: APIC: Switched APIC routing to: physical x2apic Nov 3 16:20:52.420508 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Nov 3 16:20:52.420517 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Nov 3 16:20:52.420526 kernel: kvm-guest: setup PV IPIs Nov 3 16:20:52.420535 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 3 16:20:52.420546 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Nov 3 16:20:52.420554 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Nov 3 16:20:52.420563 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Nov 3 16:20:52.420571 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Nov 3 16:20:52.420580 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Nov 3 16:20:52.420589 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 3 16:20:52.420597 kernel: Spectre V2 : Mitigation: Retpolines Nov 3 16:20:52.420608 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 3 16:20:52.420617 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Nov 3 16:20:52.420625 kernel: active return thunk: retbleed_return_thunk Nov 3 16:20:52.420634 kernel: RETBleed: Mitigation: untrained return thunk Nov 3 16:20:52.420650 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Nov 3 16:20:52.420658 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Nov 3 16:20:52.420667 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Nov 3 16:20:52.420679 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Nov 3 16:20:52.420688 kernel: active return thunk: srso_return_thunk Nov 3 16:20:52.420696 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Nov 3 16:20:52.420705 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 3 16:20:52.420713 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 3 16:20:52.420722 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 3 16:20:52.420731 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 3 16:20:52.420741 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Nov 3 16:20:52.420750 kernel: Freeing SMP alternatives memory: 32K Nov 3 16:20:52.420759 kernel: pid_max: default: 32768 minimum: 301 Nov 3 16:20:52.420767 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 3 16:20:52.420775 kernel: landlock: Up and running. Nov 3 16:20:52.420784 kernel: SELinux: Initializing. Nov 3 16:20:52.420795 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 3 16:20:52.420805 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 3 16:20:52.420814 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Nov 3 16:20:52.420823 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Nov 3 16:20:52.420832 kernel: ... version: 0 Nov 3 16:20:52.420840 kernel: ... bit width: 48 Nov 3 16:20:52.420849 kernel: ... generic registers: 6 Nov 3 16:20:52.420857 kernel: ... value mask: 0000ffffffffffff Nov 3 16:20:52.420868 kernel: ... max period: 00007fffffffffff Nov 3 16:20:52.420897 kernel: ... fixed-purpose events: 0 Nov 3 16:20:52.420905 kernel: ... event mask: 000000000000003f Nov 3 16:20:52.420914 kernel: signal: max sigframe size: 1776 Nov 3 16:20:52.420922 kernel: rcu: Hierarchical SRCU implementation. Nov 3 16:20:52.420931 kernel: rcu: Max phase no-delay instances is 400. Nov 3 16:20:52.420939 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 3 16:20:52.420951 kernel: smp: Bringing up secondary CPUs ... Nov 3 16:20:52.420959 kernel: smpboot: x86: Booting SMP configuration: Nov 3 16:20:52.420968 kernel: .... node #0, CPUs: #1 #2 #3 Nov 3 16:20:52.420976 kernel: smp: Brought up 1 node, 4 CPUs Nov 3 16:20:52.420985 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Nov 3 16:20:52.420994 kernel: Memory: 2447340K/2571752K available (14336K kernel code, 2443K rwdata, 29892K rodata, 15356K init, 2688K bss, 118472K reserved, 0K cma-reserved) Nov 3 16:20:52.421003 kernel: devtmpfs: initialized Nov 3 16:20:52.421014 kernel: x86/mm: Memory block size: 128MB Nov 3 16:20:52.421022 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 3 16:20:52.421031 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 3 16:20:52.421039 kernel: pinctrl core: initialized pinctrl subsystem Nov 3 16:20:52.421048 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 3 16:20:52.421057 kernel: audit: initializing netlink subsys (disabled) Nov 3 16:20:52.421065 kernel: audit: type=2000 audit(1762186848.709:1): state=initialized audit_enabled=0 res=1 Nov 3 16:20:52.421076 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 3 16:20:52.421085 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 3 16:20:52.421093 kernel: cpuidle: using governor menu Nov 3 16:20:52.421102 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 3 16:20:52.421110 kernel: dca service started, version 1.12.1 Nov 3 16:20:52.421119 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] Nov 3 16:20:52.421128 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry Nov 3 16:20:52.421138 kernel: PCI: Using configuration type 1 for base access Nov 3 16:20:52.421147 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 3 16:20:52.421155 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 3 16:20:52.421164 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 3 16:20:52.421173 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 3 16:20:52.421181 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 3 16:20:52.421190 kernel: ACPI: Added _OSI(Module Device) Nov 3 16:20:52.421200 kernel: ACPI: Added _OSI(Processor Device) Nov 3 16:20:52.421209 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 3 16:20:52.421218 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 3 16:20:52.421226 kernel: ACPI: Interpreter enabled Nov 3 16:20:52.421234 kernel: ACPI: PM: (supports S0 S3 S5) Nov 3 16:20:52.421243 kernel: ACPI: Using IOAPIC for interrupt routing Nov 3 16:20:52.421252 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 3 16:20:52.421260 kernel: PCI: Using E820 reservations for host bridge windows Nov 3 16:20:52.421271 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Nov 3 16:20:52.421280 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 3 16:20:52.421595 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 3 16:20:52.421791 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Nov 3 16:20:52.422025 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Nov 3 16:20:52.422043 kernel: PCI host bridge to bus 0000:00 Nov 3 16:20:52.422218 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Nov 3 16:20:52.422377 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Nov 3 16:20:52.422535 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Nov 3 16:20:52.422704 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Nov 3 16:20:52.422863 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Nov 3 16:20:52.423044 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Nov 3 16:20:52.423204 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 3 16:20:52.423397 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Nov 3 16:20:52.423585 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Nov 3 16:20:52.423769 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Nov 3 16:20:52.423997 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Nov 3 16:20:52.424170 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Nov 3 16:20:52.424340 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Nov 3 16:20:52.424524 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Nov 3 16:20:52.424708 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Nov 3 16:20:52.424900 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Nov 3 16:20:52.425081 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Nov 3 16:20:52.425264 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Nov 3 16:20:52.425437 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Nov 3 16:20:52.425608 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Nov 3 16:20:52.425791 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Nov 3 16:20:52.426037 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Nov 3 16:20:52.426282 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Nov 3 16:20:52.426517 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Nov 3 16:20:52.426765 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Nov 3 16:20:52.427019 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Nov 3 16:20:52.427319 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Nov 3 16:20:52.427524 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Nov 3 16:20:52.427719 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Nov 3 16:20:52.427935 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Nov 3 16:20:52.428111 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Nov 3 16:20:52.428290 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Nov 3 16:20:52.428461 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Nov 3 16:20:52.428481 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Nov 3 16:20:52.428493 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Nov 3 16:20:52.428504 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Nov 3 16:20:52.428515 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Nov 3 16:20:52.428526 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Nov 3 16:20:52.428534 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Nov 3 16:20:52.428543 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Nov 3 16:20:52.428554 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Nov 3 16:20:52.428563 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Nov 3 16:20:52.428571 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Nov 3 16:20:52.428580 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Nov 3 16:20:52.428588 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Nov 3 16:20:52.428597 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Nov 3 16:20:52.428605 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Nov 3 16:20:52.428616 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Nov 3 16:20:52.428624 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Nov 3 16:20:52.428633 kernel: iommu: Default domain type: Translated Nov 3 16:20:52.428650 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 3 16:20:52.428659 kernel: PCI: Using ACPI for IRQ routing Nov 3 16:20:52.428668 kernel: PCI: pci_cache_line_size set to 64 bytes Nov 3 16:20:52.428676 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Nov 3 16:20:52.428687 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Nov 3 16:20:52.428865 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Nov 3 16:20:52.429054 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Nov 3 16:20:52.429224 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Nov 3 16:20:52.429235 kernel: vgaarb: loaded Nov 3 16:20:52.429244 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Nov 3 16:20:52.429253 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Nov 3 16:20:52.429266 kernel: clocksource: Switched to clocksource kvm-clock Nov 3 16:20:52.429275 kernel: VFS: Disk quotas dquot_6.6.0 Nov 3 16:20:52.429283 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 3 16:20:52.429292 kernel: pnp: PnP ACPI init Nov 3 16:20:52.429479 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Nov 3 16:20:52.429494 kernel: pnp: PnP ACPI: found 6 devices Nov 3 16:20:52.429514 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 3 16:20:52.429523 kernel: NET: Registered PF_INET protocol family Nov 3 16:20:52.429532 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 3 16:20:52.429540 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 3 16:20:52.429549 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 3 16:20:52.429557 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 3 16:20:52.429566 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 3 16:20:52.429579 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 3 16:20:52.429588 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 3 16:20:52.429597 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 3 16:20:52.429605 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 3 16:20:52.429614 kernel: NET: Registered PF_XDP protocol family Nov 3 16:20:52.429787 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Nov 3 16:20:52.429975 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Nov 3 16:20:52.430144 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Nov 3 16:20:52.430302 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Nov 3 16:20:52.430461 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Nov 3 16:20:52.430623 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Nov 3 16:20:52.430634 kernel: PCI: CLS 0 bytes, default 64 Nov 3 16:20:52.430652 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Nov 3 16:20:52.430661 kernel: Initialise system trusted keyrings Nov 3 16:20:52.430677 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 3 16:20:52.430685 kernel: Key type asymmetric registered Nov 3 16:20:52.430694 kernel: Asymmetric key parser 'x509' registered Nov 3 16:20:52.430703 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 3 16:20:52.430711 kernel: io scheduler mq-deadline registered Nov 3 16:20:52.430720 kernel: io scheduler kyber registered Nov 3 16:20:52.430728 kernel: io scheduler bfq registered Nov 3 16:20:52.430741 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 3 16:20:52.430750 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Nov 3 16:20:52.430759 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Nov 3 16:20:52.430768 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Nov 3 16:20:52.430776 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 3 16:20:52.430785 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 3 16:20:52.430794 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 3 16:20:52.430806 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 3 16:20:52.430815 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 3 16:20:52.431013 kernel: rtc_cmos 00:04: RTC can wake from S4 Nov 3 16:20:52.431026 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Nov 3 16:20:52.431190 kernel: rtc_cmos 00:04: registered as rtc0 Nov 3 16:20:52.431354 kernel: rtc_cmos 00:04: setting system clock to 2025-11-03T16:20:50 UTC (1762186850) Nov 3 16:20:52.431529 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Nov 3 16:20:52.431540 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Nov 3 16:20:52.431549 kernel: NET: Registered PF_INET6 protocol family Nov 3 16:20:52.431557 kernel: Segment Routing with IPv6 Nov 3 16:20:52.431566 kernel: In-situ OAM (IOAM) with IPv6 Nov 3 16:20:52.431574 kernel: NET: Registered PF_PACKET protocol family Nov 3 16:20:52.431583 kernel: Key type dns_resolver registered Nov 3 16:20:52.431591 kernel: IPI shorthand broadcast: enabled Nov 3 16:20:52.431606 kernel: sched_clock: Marking stable (1779006492, 220291496)->(2049202133, -49904145) Nov 3 16:20:52.431615 kernel: registered taskstats version 1 Nov 3 16:20:52.431624 kernel: Loading compiled-in X.509 certificates Nov 3 16:20:52.431633 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 64feed854ff613845f0d7f947c8dcfa082277d4d' Nov 3 16:20:52.431650 kernel: Demotion targets for Node 0: null Nov 3 16:20:52.431659 kernel: Key type .fscrypt registered Nov 3 16:20:52.431668 kernel: Key type fscrypt-provisioning registered Nov 3 16:20:52.431681 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 3 16:20:52.431690 kernel: ima: Allocated hash algorithm: sha1 Nov 3 16:20:52.431698 kernel: ima: No architecture policies found Nov 3 16:20:52.431707 kernel: clk: Disabling unused clocks Nov 3 16:20:52.431715 kernel: Freeing unused kernel image (initmem) memory: 15356K Nov 3 16:20:52.431724 kernel: Write protecting the kernel read-only data: 45056k Nov 3 16:20:52.431732 kernel: Freeing unused kernel image (rodata/data gap) memory: 828K Nov 3 16:20:52.431746 kernel: Run /init as init process Nov 3 16:20:52.431754 kernel: with arguments: Nov 3 16:20:52.431762 kernel: /init Nov 3 16:20:52.431771 kernel: with environment: Nov 3 16:20:52.431779 kernel: HOME=/ Nov 3 16:20:52.431788 kernel: TERM=linux Nov 3 16:20:52.431796 kernel: SCSI subsystem initialized Nov 3 16:20:52.431809 kernel: libata version 3.00 loaded. Nov 3 16:20:52.432082 kernel: ahci 0000:00:1f.2: version 3.0 Nov 3 16:20:52.432117 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Nov 3 16:20:52.432323 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Nov 3 16:20:52.432541 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Nov 3 16:20:52.432749 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Nov 3 16:20:52.432966 kernel: scsi host0: ahci Nov 3 16:20:52.433153 kernel: scsi host1: ahci Nov 3 16:20:52.433335 kernel: scsi host2: ahci Nov 3 16:20:52.433523 kernel: scsi host3: ahci Nov 3 16:20:52.433718 kernel: scsi host4: ahci Nov 3 16:20:52.433958 kernel: scsi host5: ahci Nov 3 16:20:52.433973 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 26 lpm-pol 1 Nov 3 16:20:52.433983 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 26 lpm-pol 1 Nov 3 16:20:52.433992 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 26 lpm-pol 1 Nov 3 16:20:52.434001 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 26 lpm-pol 1 Nov 3 16:20:52.434010 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 26 lpm-pol 1 Nov 3 16:20:52.434019 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 26 lpm-pol 1 Nov 3 16:20:52.434032 kernel: ata2: SATA link down (SStatus 0 SControl 300) Nov 3 16:20:52.434041 kernel: ata6: SATA link down (SStatus 0 SControl 300) Nov 3 16:20:52.434050 kernel: ata5: SATA link down (SStatus 0 SControl 300) Nov 3 16:20:52.434059 kernel: ata1: SATA link down (SStatus 0 SControl 300) Nov 3 16:20:52.434068 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Nov 3 16:20:52.434076 kernel: ata4: SATA link down (SStatus 0 SControl 300) Nov 3 16:20:52.434085 kernel: ata3.00: LPM support broken, forcing max_power Nov 3 16:20:52.434096 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Nov 3 16:20:52.434105 kernel: ata3.00: applying bridge limits Nov 3 16:20:52.434114 kernel: ata3.00: LPM support broken, forcing max_power Nov 3 16:20:52.434122 kernel: ata3.00: configured for UDMA/100 Nov 3 16:20:52.434332 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Nov 3 16:20:52.434520 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Nov 3 16:20:52.434706 kernel: virtio_blk virtio1: [vda] 27000832 512-byte logical blocks (13.8 GB/12.9 GiB) Nov 3 16:20:52.434718 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 3 16:20:52.434727 kernel: GPT:16515071 != 27000831 Nov 3 16:20:52.434736 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 3 16:20:52.434744 kernel: GPT:16515071 != 27000831 Nov 3 16:20:52.434753 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 3 16:20:52.434761 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 3 16:20:52.434972 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Nov 3 16:20:52.434985 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 3 16:20:52.435172 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Nov 3 16:20:52.435184 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 3 16:20:52.435193 kernel: device-mapper: uevent: version 1.0.3 Nov 3 16:20:52.435202 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 3 16:20:52.435215 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Nov 3 16:20:52.435226 kernel: raid6: avx2x4 gen() 30329 MB/s Nov 3 16:20:52.435235 kernel: raid6: avx2x2 gen() 30936 MB/s Nov 3 16:20:52.435243 kernel: raid6: avx2x1 gen() 26008 MB/s Nov 3 16:20:52.435252 kernel: raid6: using algorithm avx2x2 gen() 30936 MB/s Nov 3 16:20:52.435263 kernel: raid6: .... xor() 19984 MB/s, rmw enabled Nov 3 16:20:52.435272 kernel: raid6: using avx2x2 recovery algorithm Nov 3 16:20:52.435281 kernel: xor: automatically using best checksumming function avx Nov 3 16:20:52.435290 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 3 16:20:52.435300 kernel: BTRFS: device fsid d2035e3e-4715-43f6-863f-bec7c8f679e4 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (180) Nov 3 16:20:52.435308 kernel: BTRFS info (device dm-0): first mount of filesystem d2035e3e-4715-43f6-863f-bec7c8f679e4 Nov 3 16:20:52.435317 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 3 16:20:52.435328 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 3 16:20:52.435337 kernel: BTRFS info (device dm-0): enabling free space tree Nov 3 16:20:52.435346 kernel: loop: module loaded Nov 3 16:20:52.435355 kernel: loop0: detected capacity change from 0 to 100136 Nov 3 16:20:52.435364 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 3 16:20:52.435374 systemd[1]: Successfully made /usr/ read-only. Nov 3 16:20:52.435387 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 3 16:20:52.435399 systemd[1]: Detected virtualization kvm. Nov 3 16:20:52.435409 systemd[1]: Detected architecture x86-64. Nov 3 16:20:52.435418 systemd[1]: Running in initrd. Nov 3 16:20:52.435427 systemd[1]: No hostname configured, using default hostname. Nov 3 16:20:52.435437 systemd[1]: Hostname set to . Nov 3 16:20:52.435446 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 3 16:20:52.435457 systemd[1]: Queued start job for default target initrd.target. Nov 3 16:20:52.435467 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 3 16:20:52.435476 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 3 16:20:52.435485 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 3 16:20:52.435498 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 3 16:20:52.435507 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 3 16:20:52.435519 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 3 16:20:52.435529 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 3 16:20:52.435539 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 3 16:20:52.435548 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 3 16:20:52.435557 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 3 16:20:52.435567 systemd[1]: Reached target paths.target - Path Units. Nov 3 16:20:52.435578 systemd[1]: Reached target slices.target - Slice Units. Nov 3 16:20:52.435587 systemd[1]: Reached target swap.target - Swaps. Nov 3 16:20:52.435596 systemd[1]: Reached target timers.target - Timer Units. Nov 3 16:20:52.435606 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 3 16:20:52.435615 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 3 16:20:52.435624 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 3 16:20:52.435634 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 3 16:20:52.435655 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 3 16:20:52.435665 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 3 16:20:52.435675 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 3 16:20:52.435684 systemd[1]: Reached target sockets.target - Socket Units. Nov 3 16:20:52.435694 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 3 16:20:52.435703 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 3 16:20:52.435715 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 3 16:20:52.435725 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 3 16:20:52.435735 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 3 16:20:52.435744 systemd[1]: Starting systemd-fsck-usr.service... Nov 3 16:20:52.435753 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 3 16:20:52.435763 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 3 16:20:52.435772 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 3 16:20:52.435785 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 3 16:20:52.435794 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 3 16:20:52.435803 systemd[1]: Finished systemd-fsck-usr.service. Nov 3 16:20:52.435813 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 3 16:20:52.435862 systemd-journald[314]: Collecting audit messages is disabled. Nov 3 16:20:52.435899 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 3 16:20:52.435908 kernel: Bridge firewalling registered Nov 3 16:20:52.435920 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 3 16:20:52.435930 systemd-journald[314]: Journal started Nov 3 16:20:52.435949 systemd-journald[314]: Runtime Journal (/run/log/journal/9753c80dcc2341809875f60a72ab6720) is 6M, max 48.2M, 42.2M free. Nov 3 16:20:52.432520 systemd-modules-load[317]: Inserted module 'br_netfilter' Nov 3 16:20:52.468050 systemd[1]: Started systemd-journald.service - Journal Service. Nov 3 16:20:52.472595 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 3 16:20:52.474430 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 3 16:20:52.481109 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 3 16:20:52.488151 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 3 16:20:52.493165 systemd-tmpfiles[334]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 3 16:20:52.502132 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 3 16:20:52.506284 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 3 16:20:52.509502 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 3 16:20:52.516143 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 3 16:20:52.517536 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 3 16:20:52.531572 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 3 16:20:52.536068 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 3 16:20:52.542008 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 3 16:20:52.568958 dracut-cmdline[359]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=e9d538d1fb909beabac60fa40d47676de06d795b9bab2159a3819b90e410c77a Nov 3 16:20:52.595866 systemd-resolved[344]: Positive Trust Anchors: Nov 3 16:20:52.595896 systemd-resolved[344]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 3 16:20:52.595901 systemd-resolved[344]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 3 16:20:52.595933 systemd-resolved[344]: 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 Nov 3 16:20:52.633398 systemd-resolved[344]: Defaulting to hostname 'linux'. Nov 3 16:20:52.634840 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 3 16:20:52.635589 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 3 16:20:52.700914 kernel: Loading iSCSI transport class v2.0-870. Nov 3 16:20:52.714918 kernel: iscsi: registered transport (tcp) Nov 3 16:20:52.740299 kernel: iscsi: registered transport (qla4xxx) Nov 3 16:20:52.740348 kernel: QLogic iSCSI HBA Driver Nov 3 16:20:52.768559 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 3 16:20:52.794367 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 3 16:20:52.799715 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 3 16:20:52.868522 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 3 16:20:52.871047 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 3 16:20:52.873468 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 3 16:20:52.938178 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 3 16:20:52.944191 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 3 16:20:52.989036 systemd-udevd[600]: Using default interface naming scheme 'v257'. Nov 3 16:20:53.008930 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 3 16:20:53.016221 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 3 16:20:53.049285 dracut-pre-trigger[673]: rd.md=0: removing MD RAID activation Nov 3 16:20:53.049865 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 3 16:20:53.052822 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 3 16:20:53.096355 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 3 16:20:53.100255 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 3 16:20:53.113116 systemd-networkd[709]: lo: Link UP Nov 3 16:20:53.113126 systemd-networkd[709]: lo: Gained carrier Nov 3 16:20:53.113740 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 3 16:20:53.115455 systemd[1]: Reached target network.target - Network. Nov 3 16:20:53.209794 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 3 16:20:53.214025 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 3 16:20:53.288560 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Nov 3 16:20:53.291311 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 3 16:20:53.302673 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Nov 3 16:20:53.327910 kernel: cryptd: max_cpu_qlen set to 1000 Nov 3 16:20:53.330066 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Nov 3 16:20:53.340915 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Nov 3 16:20:53.346240 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 3 16:20:53.350772 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 3 16:20:53.356454 kernel: AES CTR mode by8 optimization enabled Nov 3 16:20:53.357490 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 3 16:20:53.361537 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 3 16:20:53.366351 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 3 16:20:53.372708 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 3 16:20:53.379959 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 3 16:20:53.381717 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 3 16:20:53.384617 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 3 16:20:53.385568 systemd-networkd[709]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 3 16:20:53.385574 systemd-networkd[709]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 3 16:20:53.387925 systemd-networkd[709]: eth0: Link UP Nov 3 16:20:53.400323 disk-uuid[823]: Primary Header is updated. Nov 3 16:20:53.400323 disk-uuid[823]: Secondary Entries is updated. Nov 3 16:20:53.400323 disk-uuid[823]: Secondary Header is updated. Nov 3 16:20:53.388566 systemd-networkd[709]: eth0: Gained carrier Nov 3 16:20:53.388577 systemd-networkd[709]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 3 16:20:53.395529 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 3 16:20:53.412053 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 3 16:20:53.415844 systemd-networkd[709]: eth0: DHCPv4 address 10.0.0.55/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 3 16:20:53.518029 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 3 16:20:54.457570 disk-uuid[832]: Warning: The kernel is still using the old partition table. Nov 3 16:20:54.457570 disk-uuid[832]: The new table will be used at the next reboot or after you Nov 3 16:20:54.457570 disk-uuid[832]: run partprobe(8) or kpartx(8) Nov 3 16:20:54.457570 disk-uuid[832]: The operation has completed successfully. Nov 3 16:20:54.466843 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 3 16:20:54.467072 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 3 16:20:54.472671 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 3 16:20:54.517923 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (859) Nov 3 16:20:54.521137 kernel: BTRFS info (device vda6): first mount of filesystem 78119da2-288c-42f1-b313-2f60b0c42ea0 Nov 3 16:20:54.521195 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 3 16:20:54.525247 kernel: BTRFS info (device vda6): turning on async discard Nov 3 16:20:54.525282 kernel: BTRFS info (device vda6): enabling free space tree Nov 3 16:20:54.533931 kernel: BTRFS info (device vda6): last unmount of filesystem 78119da2-288c-42f1-b313-2f60b0c42ea0 Nov 3 16:20:54.535068 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 3 16:20:54.538271 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 3 16:20:54.989165 ignition[878]: Ignition 2.22.0 Nov 3 16:20:54.989181 ignition[878]: Stage: fetch-offline Nov 3 16:20:54.989245 ignition[878]: no configs at "/usr/lib/ignition/base.d" Nov 3 16:20:54.989266 ignition[878]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 3 16:20:54.989392 ignition[878]: parsed url from cmdline: "" Nov 3 16:20:54.989396 ignition[878]: no config URL provided Nov 3 16:20:54.989402 ignition[878]: reading system config file "/usr/lib/ignition/user.ign" Nov 3 16:20:54.989415 ignition[878]: no config at "/usr/lib/ignition/user.ign" Nov 3 16:20:54.989467 ignition[878]: op(1): [started] loading QEMU firmware config module Nov 3 16:20:54.989472 ignition[878]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 3 16:20:55.011171 ignition[878]: op(1): [finished] loading QEMU firmware config module Nov 3 16:20:55.013521 ignition[878]: parsing config with SHA512: d5901684715173833c523131365ed2611837aec75f876ad3065e93dfdae120024211862dd2d3b5949656f084fb46099dfc6189f5095002b10aba64a60cccc544 Nov 3 16:20:55.018505 unknown[878]: fetched base config from "system" Nov 3 16:20:55.018810 ignition[878]: fetch-offline: fetch-offline passed Nov 3 16:20:55.018519 unknown[878]: fetched user config from "qemu" Nov 3 16:20:55.018874 ignition[878]: Ignition finished successfully Nov 3 16:20:55.023248 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 3 16:20:55.024122 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Nov 3 16:20:55.025504 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 3 16:20:55.071104 ignition[891]: Ignition 2.22.0 Nov 3 16:20:55.071116 ignition[891]: Stage: kargs Nov 3 16:20:55.071248 ignition[891]: no configs at "/usr/lib/ignition/base.d" Nov 3 16:20:55.071258 ignition[891]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 3 16:20:55.072008 ignition[891]: kargs: kargs passed Nov 3 16:20:55.072066 ignition[891]: Ignition finished successfully Nov 3 16:20:55.079035 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 3 16:20:55.083331 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 3 16:20:55.143160 ignition[899]: Ignition 2.22.0 Nov 3 16:20:55.143174 ignition[899]: Stage: disks Nov 3 16:20:55.143320 ignition[899]: no configs at "/usr/lib/ignition/base.d" Nov 3 16:20:55.143330 ignition[899]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 3 16:20:55.143940 ignition[899]: disks: disks passed Nov 3 16:20:55.143992 ignition[899]: Ignition finished successfully Nov 3 16:20:55.153215 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 3 16:20:55.156509 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 3 16:20:55.157444 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 3 16:20:55.160671 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 3 16:20:55.164651 systemd[1]: Reached target sysinit.target - System Initialization. Nov 3 16:20:55.168614 systemd[1]: Reached target basic.target - Basic System. Nov 3 16:20:55.173189 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 3 16:20:55.226989 systemd-fsck[909]: ROOT: clean, 15/456736 files, 38230/456704 blocks Nov 3 16:20:55.359106 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 3 16:20:55.361012 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 3 16:20:55.399022 systemd-networkd[709]: eth0: Gained IPv6LL Nov 3 16:20:55.474915 kernel: EXT4-fs (vda9): mounted filesystem 1ffbd672-550b-441b-a3fe-835a1dd6d831 r/w with ordered data mode. Quota mode: none. Nov 3 16:20:55.475837 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 3 16:20:55.477153 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 3 16:20:55.480670 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 3 16:20:55.484040 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 3 16:20:55.487701 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Nov 3 16:20:55.487766 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 3 16:20:55.487806 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 3 16:20:55.503390 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 3 16:20:55.509668 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (917) Nov 3 16:20:55.509739 kernel: BTRFS info (device vda6): first mount of filesystem 78119da2-288c-42f1-b313-2f60b0c42ea0 Nov 3 16:20:55.509752 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 3 16:20:55.511366 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 3 16:20:55.517216 kernel: BTRFS info (device vda6): turning on async discard Nov 3 16:20:55.517262 kernel: BTRFS info (device vda6): enabling free space tree Nov 3 16:20:55.619816 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 3 16:20:55.683858 initrd-setup-root[941]: cut: /sysroot/etc/passwd: No such file or directory Nov 3 16:20:55.688321 initrd-setup-root[948]: cut: /sysroot/etc/group: No such file or directory Nov 3 16:20:55.694407 initrd-setup-root[955]: cut: /sysroot/etc/shadow: No such file or directory Nov 3 16:20:55.701439 initrd-setup-root[962]: cut: /sysroot/etc/gshadow: No such file or directory Nov 3 16:20:55.803240 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 3 16:20:55.807290 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 3 16:20:55.810058 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 3 16:20:55.830128 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 3 16:20:55.832930 kernel: BTRFS info (device vda6): last unmount of filesystem 78119da2-288c-42f1-b313-2f60b0c42ea0 Nov 3 16:20:55.844019 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 3 16:20:55.879937 ignition[1031]: INFO : Ignition 2.22.0 Nov 3 16:20:55.879937 ignition[1031]: INFO : Stage: mount Nov 3 16:20:55.882503 ignition[1031]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 3 16:20:55.882503 ignition[1031]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 3 16:20:55.886438 ignition[1031]: INFO : mount: mount passed Nov 3 16:20:55.887603 ignition[1031]: INFO : Ignition finished successfully Nov 3 16:20:55.891844 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 3 16:20:55.896278 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 3 16:20:55.926532 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 3 16:20:55.950896 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1043) Nov 3 16:20:55.954338 kernel: BTRFS info (device vda6): first mount of filesystem 78119da2-288c-42f1-b313-2f60b0c42ea0 Nov 3 16:20:55.954365 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Nov 3 16:20:55.958190 kernel: BTRFS info (device vda6): turning on async discard Nov 3 16:20:55.958212 kernel: BTRFS info (device vda6): enabling free space tree Nov 3 16:20:55.960092 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 3 16:20:56.011991 ignition[1060]: INFO : Ignition 2.22.0 Nov 3 16:20:56.011991 ignition[1060]: INFO : Stage: files Nov 3 16:20:56.053594 ignition[1060]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 3 16:20:56.053594 ignition[1060]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 3 16:20:56.053594 ignition[1060]: DEBUG : files: compiled without relabeling support, skipping Nov 3 16:20:56.053594 ignition[1060]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 3 16:20:56.053594 ignition[1060]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 3 16:20:56.063781 ignition[1060]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 3 16:20:56.063781 ignition[1060]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 3 16:20:56.063781 ignition[1060]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 3 16:20:56.063781 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 3 16:20:56.063781 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 3 16:20:56.056150 unknown[1060]: wrote ssh authorized keys file for user: core Nov 3 16:20:56.343930 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 3 16:20:56.347125 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 3 16:20:56.347125 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 3 16:20:56.389059 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 3 16:20:56.389059 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 3 16:20:56.397576 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-x86-64.raw: attempt #1 Nov 3 16:20:56.727112 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 3 16:20:57.797844 ignition[1060]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 3 16:20:57.797844 ignition[1060]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Nov 3 16:20:57.804190 ignition[1060]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 3 16:20:57.811924 ignition[1060]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Nov 3 16:20:57.811924 ignition[1060]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Nov 3 16:20:57.817499 ignition[1060]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Nov 3 16:20:57.836151 ignition[1060]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Nov 3 16:20:57.845822 ignition[1060]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Nov 3 16:20:57.848356 ignition[1060]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Nov 3 16:20:57.848356 ignition[1060]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 3 16:20:57.848356 ignition[1060]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 3 16:20:57.848356 ignition[1060]: INFO : files: files passed Nov 3 16:20:57.848356 ignition[1060]: INFO : Ignition finished successfully Nov 3 16:20:57.854687 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 3 16:20:57.860854 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 3 16:20:57.862422 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 3 16:20:57.880615 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 3 16:20:57.880764 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 3 16:20:57.889188 initrd-setup-root-after-ignition[1091]: grep: /sysroot/oem/oem-release: No such file or directory Nov 3 16:20:57.894541 initrd-setup-root-after-ignition[1093]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 3 16:20:57.894541 initrd-setup-root-after-ignition[1093]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 3 16:20:57.899677 initrd-setup-root-after-ignition[1097]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 3 16:20:57.903754 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 3 16:20:57.907992 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 3 16:20:57.912540 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 3 16:20:57.977526 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 3 16:20:57.979118 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 3 16:20:57.983513 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 3 16:20:57.986678 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 3 16:20:57.990101 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 3 16:20:57.992609 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 3 16:20:58.037966 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 3 16:20:58.040747 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 3 16:20:58.069849 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 3 16:20:58.070009 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 3 16:20:58.070864 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 3 16:20:58.077011 systemd[1]: Stopped target timers.target - Timer Units. Nov 3 16:20:58.077831 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 3 16:20:58.077994 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 3 16:20:58.084513 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 3 16:20:58.087851 systemd[1]: Stopped target basic.target - Basic System. Nov 3 16:20:58.088709 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 3 16:20:58.092577 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 3 16:20:58.095835 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 3 16:20:58.099328 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 3 16:20:58.099860 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 3 16:20:58.105957 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 3 16:20:58.108999 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 3 16:20:58.112751 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 3 16:20:58.115989 systemd[1]: Stopped target swap.target - Swaps. Nov 3 16:20:58.118935 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 3 16:20:58.119100 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 3 16:20:58.123745 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 3 16:20:58.124587 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 3 16:20:58.129253 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 3 16:20:58.129411 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 3 16:20:58.132582 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 3 16:20:58.132701 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 3 16:20:58.139086 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 3 16:20:58.139232 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 3 16:20:58.140419 systemd[1]: Stopped target paths.target - Path Units. Nov 3 16:20:58.140871 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 3 16:20:58.149954 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 3 16:20:58.150787 systemd[1]: Stopped target slices.target - Slice Units. Nov 3 16:20:58.151316 systemd[1]: Stopped target sockets.target - Socket Units. Nov 3 16:20:58.157991 systemd[1]: iscsid.socket: Deactivated successfully. Nov 3 16:20:58.158100 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 3 16:20:58.158524 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 3 16:20:58.158623 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 3 16:20:58.163494 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 3 16:20:58.163628 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 3 16:20:58.166335 systemd[1]: ignition-files.service: Deactivated successfully. Nov 3 16:20:58.166456 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 3 16:20:58.173418 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 3 16:20:58.174374 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 3 16:20:58.174485 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 3 16:20:58.191578 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 3 16:20:58.192220 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 3 16:20:58.192363 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 3 16:20:58.198038 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 3 16:20:58.198170 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 3 16:20:58.198782 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 3 16:20:58.198901 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 3 16:20:58.215364 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 3 16:20:58.215502 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 3 16:20:58.220748 ignition[1117]: INFO : Ignition 2.22.0 Nov 3 16:20:58.220748 ignition[1117]: INFO : Stage: umount Nov 3 16:20:58.220748 ignition[1117]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 3 16:20:58.220748 ignition[1117]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Nov 3 16:20:58.220748 ignition[1117]: INFO : umount: umount passed Nov 3 16:20:58.220748 ignition[1117]: INFO : Ignition finished successfully Nov 3 16:20:58.221277 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 3 16:20:58.221435 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 3 16:20:58.224800 systemd[1]: Stopped target network.target - Network. Nov 3 16:20:58.225635 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 3 16:20:58.225734 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 3 16:20:58.232138 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 3 16:20:58.232210 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 3 16:20:58.233350 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 3 16:20:58.233407 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 3 16:20:58.237409 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 3 16:20:58.237469 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 3 16:20:58.240595 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 3 16:20:58.243558 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 3 16:20:58.249236 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 3 16:20:58.258594 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 3 16:20:58.258737 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 3 16:20:58.266084 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 3 16:20:58.266225 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 3 16:20:58.271349 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 3 16:20:58.271955 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 3 16:20:58.272024 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 3 16:20:58.279719 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 3 16:20:58.283461 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 3 16:20:58.283654 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 3 16:20:58.284806 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 3 16:20:58.284861 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 3 16:20:58.285432 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 3 16:20:58.285492 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 3 16:20:58.295280 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 3 16:20:58.309260 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 3 16:20:58.315235 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 3 16:20:58.319124 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 3 16:20:58.319203 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 3 16:20:58.325059 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 3 16:20:58.325262 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 3 16:20:58.329201 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 3 16:20:58.329257 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 3 16:20:58.332913 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 3 16:20:58.332960 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 3 16:20:58.333613 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 3 16:20:58.333684 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 3 16:20:58.334474 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 3 16:20:58.334531 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 3 16:20:58.335282 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 3 16:20:58.335331 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 3 16:20:58.337259 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 3 16:20:58.337299 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 3 16:20:58.337353 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 3 16:20:58.337624 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 3 16:20:58.337672 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 3 16:20:58.337909 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 3 16:20:58.337956 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 3 16:20:58.372523 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 3 16:20:58.372671 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 3 16:20:58.377471 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 3 16:20:58.377605 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 3 16:20:58.378873 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 3 16:20:58.386006 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 3 16:20:58.401236 systemd[1]: Switching root. Nov 3 16:20:58.444105 systemd-journald[314]: Journal stopped Nov 3 16:20:59.858021 systemd-journald[314]: Received SIGTERM from PID 1 (systemd). Nov 3 16:20:59.858095 kernel: SELinux: policy capability network_peer_controls=1 Nov 3 16:20:59.858122 kernel: SELinux: policy capability open_perms=1 Nov 3 16:20:59.858139 kernel: SELinux: policy capability extended_socket_class=1 Nov 3 16:20:59.858203 kernel: SELinux: policy capability always_check_network=0 Nov 3 16:20:59.858220 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 3 16:20:59.858234 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 3 16:20:59.858247 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 3 16:20:59.858262 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 3 16:20:59.858274 kernel: SELinux: policy capability userspace_initial_context=0 Nov 3 16:20:59.858287 kernel: audit: type=1403 audit(1762186858.902:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 3 16:20:59.858308 systemd[1]: Successfully loaded SELinux policy in 72.143ms. Nov 3 16:20:59.858332 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.188ms. Nov 3 16:20:59.858346 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 3 16:20:59.858362 systemd[1]: Detected virtualization kvm. Nov 3 16:20:59.858375 systemd[1]: Detected architecture x86-64. Nov 3 16:20:59.858388 systemd[1]: Detected first boot. Nov 3 16:20:59.858403 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Nov 3 16:20:59.858423 kernel: Guest personality initialized and is inactive Nov 3 16:20:59.858436 zram_generator::config[1163]: No configuration found. Nov 3 16:20:59.858449 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Nov 3 16:20:59.858461 kernel: Initialized host personality Nov 3 16:20:59.858474 kernel: NET: Registered PF_VSOCK protocol family Nov 3 16:20:59.858500 systemd[1]: Populated /etc with preset unit settings. Nov 3 16:20:59.858514 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 3 16:20:59.858543 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 3 16:20:59.858560 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 3 16:20:59.858573 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 3 16:20:59.858594 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 3 16:20:59.858615 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 3 16:20:59.858628 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 3 16:20:59.858641 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 3 16:20:59.858654 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 3 16:20:59.858667 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 3 16:20:59.858680 systemd[1]: Created slice user.slice - User and Session Slice. Nov 3 16:20:59.858692 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 3 16:20:59.858714 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 3 16:20:59.858727 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 3 16:20:59.858740 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 3 16:20:59.858754 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 3 16:20:59.858767 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 3 16:20:59.858779 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 3 16:20:59.858802 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 3 16:20:59.858815 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 3 16:20:59.858830 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 3 16:20:59.858843 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 3 16:20:59.858856 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 3 16:20:59.858868 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 3 16:20:59.858983 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 3 16:20:59.859007 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 3 16:20:59.859020 systemd[1]: Reached target slices.target - Slice Units. Nov 3 16:20:59.859036 systemd[1]: Reached target swap.target - Swaps. Nov 3 16:20:59.859048 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 3 16:20:59.859061 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 3 16:20:59.859074 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 3 16:20:59.859086 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 3 16:20:59.859107 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 3 16:20:59.859120 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 3 16:20:59.859133 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 3 16:20:59.859146 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 3 16:20:59.859159 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 3 16:20:59.859172 systemd[1]: Mounting media.mount - External Media Directory... Nov 3 16:20:59.859184 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:20:59.859205 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 3 16:20:59.859218 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 3 16:20:59.859231 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 3 16:20:59.859245 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). Nov 3 16:20:59.859258 systemd[1]: Reached target machines.target - Containers. Nov 3 16:20:59.859271 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 3 16:20:59.859284 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 3 16:20:59.859305 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 3 16:20:59.859318 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 3 16:20:59.859334 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 3 16:20:59.859347 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 3 16:20:59.859360 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 3 16:20:59.859372 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 3 16:20:59.859385 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 3 16:20:59.859409 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 3 16:20:59.859422 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 3 16:20:59.859435 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 3 16:20:59.859447 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 3 16:20:59.859460 systemd[1]: Stopped systemd-fsck-usr.service. Nov 3 16:20:59.859473 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 3 16:20:59.859503 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 3 16:20:59.859517 kernel: fuse: init (API version 7.41) Nov 3 16:20:59.859532 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 3 16:20:59.859546 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 3 16:20:59.859558 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 3 16:20:59.859570 kernel: ACPI: bus type drm_connector registered Nov 3 16:20:59.859582 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 3 16:20:59.859595 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 3 16:20:59.859635 systemd-journald[1248]: Collecting audit messages is disabled. Nov 3 16:20:59.859659 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:20:59.859681 systemd-journald[1248]: Journal started Nov 3 16:20:59.859704 systemd-journald[1248]: Runtime Journal (/run/log/journal/9753c80dcc2341809875f60a72ab6720) is 6M, max 48.2M, 42.2M free. Nov 3 16:20:59.873233 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 3 16:20:59.873264 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 3 16:20:59.873280 systemd[1]: Mounted media.mount - External Media Directory. Nov 3 16:20:59.873308 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 3 16:20:59.494783 systemd[1]: Queued start job for default target multi-user.target. Nov 3 16:20:59.521646 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Nov 3 16:20:59.522288 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 3 16:20:59.879145 systemd[1]: Started systemd-journald.service - Journal Service. Nov 3 16:20:59.881564 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 3 16:20:59.883423 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 3 16:20:59.885334 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 3 16:20:59.887589 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 3 16:20:59.890018 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 3 16:20:59.890255 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 3 16:20:59.892440 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 3 16:20:59.892675 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 3 16:20:59.894798 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 3 16:20:59.895039 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 3 16:20:59.897059 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 3 16:20:59.897280 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 3 16:20:59.899534 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 3 16:20:59.899757 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 3 16:20:59.901777 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 3 16:20:59.902020 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 3 16:20:59.904141 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 3 16:20:59.906389 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 3 16:20:59.909445 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 3 16:20:59.911911 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 3 16:20:59.932009 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 3 16:20:59.934185 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 3 16:20:59.937460 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 3 16:20:59.940323 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 3 16:20:59.942209 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 3 16:20:59.942306 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 3 16:20:59.944988 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 3 16:20:59.947201 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 3 16:20:59.959024 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 3 16:20:59.962526 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 3 16:20:59.964828 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 3 16:20:59.967049 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 3 16:20:59.969279 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 3 16:20:59.972041 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 3 16:20:59.972388 systemd-journald[1248]: Time spent on flushing to /var/log/journal/9753c80dcc2341809875f60a72ab6720 is 23.620ms for 946 entries. Nov 3 16:20:59.972388 systemd-journald[1248]: System Journal (/var/log/journal/9753c80dcc2341809875f60a72ab6720) is 8M, max 163.5M, 155.5M free. Nov 3 16:21:00.016471 systemd-journald[1248]: Received client request to flush runtime journal. Nov 3 16:21:00.016554 kernel: loop1: detected capacity change from 0 to 119080 Nov 3 16:20:59.976928 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 3 16:20:59.980688 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 3 16:20:59.984320 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 3 16:20:59.987691 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 3 16:20:59.989834 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 3 16:21:00.000586 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 3 16:21:00.007060 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 3 16:21:00.013062 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 3 16:21:00.015534 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 3 16:21:00.018370 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 3 16:21:00.028911 kernel: loop2: detected capacity change from 0 to 111544 Nov 3 16:21:00.038735 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 3 16:21:00.045185 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 3 16:21:00.049386 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 3 16:21:00.051728 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 3 16:21:00.065405 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 3 16:21:00.069910 kernel: loop3: detected capacity change from 0 to 229808 Nov 3 16:21:00.080734 systemd-tmpfiles[1301]: ACLs are not supported, ignoring. Nov 3 16:21:00.080753 systemd-tmpfiles[1301]: ACLs are not supported, ignoring. Nov 3 16:21:00.085585 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 3 16:21:00.092896 kernel: loop4: detected capacity change from 0 to 119080 Nov 3 16:21:00.101905 kernel: loop5: detected capacity change from 0 to 111544 Nov 3 16:21:00.110660 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 3 16:21:00.112625 kernel: loop6: detected capacity change from 0 to 229808 Nov 3 16:21:00.118612 (sd-merge)[1308]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw'. Nov 3 16:21:00.124210 (sd-merge)[1308]: Merged extensions into '/usr'. Nov 3 16:21:00.128974 systemd[1]: Reload requested from client PID 1282 ('systemd-sysext') (unit systemd-sysext.service)... Nov 3 16:21:00.128994 systemd[1]: Reloading... Nov 3 16:21:00.194907 zram_generator::config[1342]: No configuration found. Nov 3 16:21:00.212835 systemd-resolved[1300]: Positive Trust Anchors: Nov 3 16:21:00.213230 systemd-resolved[1300]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 3 16:21:00.213283 systemd-resolved[1300]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 3 16:21:00.213363 systemd-resolved[1300]: 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 Nov 3 16:21:00.217836 systemd-resolved[1300]: Defaulting to hostname 'linux'. Nov 3 16:21:00.381177 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 3 16:21:00.381561 systemd[1]: Reloading finished in 252 ms. Nov 3 16:21:00.418587 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 3 16:21:00.421217 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 3 16:21:00.425933 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 3 16:21:00.453395 systemd[1]: Starting ensure-sysext.service... Nov 3 16:21:00.455757 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 3 16:21:00.475238 systemd-tmpfiles[1379]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 3 16:21:00.475278 systemd-tmpfiles[1379]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 3 16:21:00.475594 systemd-tmpfiles[1379]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 3 16:21:00.475898 systemd-tmpfiles[1379]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 3 16:21:00.476832 systemd-tmpfiles[1379]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 3 16:21:00.477130 systemd-tmpfiles[1379]: ACLs are not supported, ignoring. Nov 3 16:21:00.477207 systemd-tmpfiles[1379]: ACLs are not supported, ignoring. Nov 3 16:21:00.489778 systemd-tmpfiles[1379]: Detected autofs mount point /boot during canonicalization of boot. Nov 3 16:21:00.489792 systemd-tmpfiles[1379]: Skipping /boot Nov 3 16:21:00.493636 systemd[1]: Reload requested from client PID 1378 ('systemctl') (unit ensure-sysext.service)... Nov 3 16:21:00.493654 systemd[1]: Reloading... Nov 3 16:21:00.500690 systemd-tmpfiles[1379]: Detected autofs mount point /boot during canonicalization of boot. Nov 3 16:21:00.500705 systemd-tmpfiles[1379]: Skipping /boot Nov 3 16:21:00.556918 zram_generator::config[1409]: No configuration found. Nov 3 16:21:01.101673 systemd[1]: Reloading finished in 607 ms. Nov 3 16:21:01.133723 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 3 16:21:01.153257 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 3 16:21:01.164374 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 3 16:21:01.167251 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 3 16:21:01.187617 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 3 16:21:01.191308 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 3 16:21:01.196933 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 3 16:21:01.202120 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 3 16:21:01.208036 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:21:01.208212 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 3 16:21:01.210197 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 3 16:21:01.214264 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 3 16:21:01.218614 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 3 16:21:01.220470 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 3 16:21:01.220584 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 3 16:21:01.220675 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:21:01.229826 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:21:01.230464 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 3 16:21:01.230791 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 3 16:21:01.231043 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 3 16:21:01.231278 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:21:01.232176 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 3 16:21:01.234938 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 3 16:21:01.235340 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 3 16:21:01.238267 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 3 16:21:01.238488 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 3 16:21:01.242783 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 3 16:21:01.243685 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 3 16:21:01.249298 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 3 16:21:01.249867 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 3 16:21:01.251642 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 3 16:21:01.257218 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:21:01.257455 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 3 16:21:01.259719 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 3 16:21:01.262113 systemd-udevd[1453]: Using default interface naming scheme 'v257'. Nov 3 16:21:01.264297 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 3 16:21:01.268553 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 3 16:21:01.272180 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 3 16:21:01.274129 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 3 16:21:01.274248 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 3 16:21:01.274397 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 3 16:21:01.282604 systemd[1]: Finished ensure-sysext.service. Nov 3 16:21:01.286626 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 3 16:21:01.291076 augenrules[1486]: No rules Nov 3 16:21:01.287122 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 3 16:21:01.292427 systemd[1]: audit-rules.service: Deactivated successfully. Nov 3 16:21:01.297095 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 3 16:21:01.299234 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 3 16:21:01.299492 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 3 16:21:01.301747 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 3 16:21:01.301993 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 3 16:21:01.304041 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 3 16:21:01.304259 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 3 16:21:01.310078 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 3 16:21:01.310134 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 3 16:21:01.312287 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 3 16:21:01.314365 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 3 16:21:01.320949 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 3 16:21:01.384938 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 3 16:21:01.389544 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). Nov 3 16:21:01.418046 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 3 16:21:01.472832 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 3 16:21:01.549951 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Nov 3 16:21:01.558773 systemd-networkd[1506]: lo: Link UP Nov 3 16:21:01.558788 systemd-networkd[1506]: lo: Gained carrier Nov 3 16:21:01.565910 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Nov 3 16:21:01.572901 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Nov 3 16:21:01.581164 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Nov 3 16:21:01.580987 systemd-networkd[1506]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 3 16:21:01.580993 systemd-networkd[1506]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 3 16:21:01.581790 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 3 16:21:01.583829 systemd[1]: Reached target network.target - Network. Nov 3 16:21:01.585022 systemd-networkd[1506]: eth0: Link UP Nov 3 16:21:01.585308 systemd-networkd[1506]: eth0: Gained carrier Nov 3 16:21:01.585330 systemd-networkd[1506]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 3 16:21:01.585534 systemd[1]: Reached target time-set.target - System Time Set. Nov 3 16:21:01.589621 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 3 16:21:01.593675 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 3 16:21:01.596899 kernel: mousedev: PS/2 mouse device common for all mice Nov 3 16:21:01.600191 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 3 16:21:01.605988 systemd-networkd[1506]: eth0: DHCPv4 address 10.0.0.55/16, gateway 10.0.0.1 acquired from 10.0.0.1 Nov 3 16:21:01.607614 systemd-timesyncd[1500]: Network configuration changed, trying to establish connection. Nov 3 16:21:01.610048 systemd-timesyncd[1500]: Contacted time server 10.0.0.1:123 (10.0.0.1). Nov 3 16:21:01.610134 systemd-timesyncd[1500]: Initial clock synchronization to Mon 2025-11-03 16:21:01.713432 UTC. Nov 3 16:21:01.648344 kernel: ACPI: button: Power Button [PWRF] Nov 3 16:21:01.706970 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 3 16:21:01.711057 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 3 16:21:01.780601 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 3 16:21:01.807666 kernel: kvm_amd: TSC scaling supported Nov 3 16:21:01.807730 kernel: kvm_amd: Nested Virtualization enabled Nov 3 16:21:01.807769 kernel: kvm_amd: Nested Paging enabled Nov 3 16:21:01.807783 kernel: kvm_amd: LBR virtualization supported Nov 3 16:21:01.807797 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Nov 3 16:21:01.808833 kernel: kvm_amd: Virtual GIF supported Nov 3 16:21:01.842939 kernel: EDAC MC: Ver: 3.0.0 Nov 3 16:21:01.956184 ldconfig[1450]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 3 16:21:01.958388 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 3 16:21:01.963491 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 3 16:21:01.967092 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 3 16:21:02.002077 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 3 16:21:02.004182 systemd[1]: Reached target sysinit.target - System Initialization. Nov 3 16:21:02.005987 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 3 16:21:02.007982 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 3 16:21:02.009971 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 3 16:21:02.011974 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 3 16:21:02.013798 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 3 16:21:02.015810 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 3 16:21:02.017774 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 3 16:21:02.017807 systemd[1]: Reached target paths.target - Path Units. Nov 3 16:21:02.019246 systemd[1]: Reached target timers.target - Timer Units. Nov 3 16:21:02.021551 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 3 16:21:02.024869 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 3 16:21:02.029311 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 3 16:21:02.031528 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 3 16:21:02.033634 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 3 16:21:02.039764 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 3 16:21:02.042746 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 3 16:21:02.046583 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 3 16:21:02.050741 systemd[1]: Reached target sockets.target - Socket Units. Nov 3 16:21:02.052614 systemd[1]: Reached target basic.target - Basic System. Nov 3 16:21:02.054464 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 3 16:21:02.054557 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 3 16:21:02.057500 systemd[1]: Starting containerd.service - containerd container runtime... Nov 3 16:21:02.061548 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 3 16:21:02.065671 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 3 16:21:02.069229 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 3 16:21:02.084720 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 3 16:21:02.086613 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). Nov 3 16:21:02.089596 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 3 16:21:02.090771 jq[1569]: false Nov 3 16:21:02.091724 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 3 16:21:02.095255 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 3 16:21:02.098398 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 3 16:21:02.102251 extend-filesystems[1570]: Found /dev/vda6 Nov 3 16:21:02.106607 extend-filesystems[1570]: Found /dev/vda9 Nov 3 16:21:02.108629 extend-filesystems[1570]: Checking size of /dev/vda9 Nov 3 16:21:02.113959 oslogin_cache_refresh[1571]: Refreshing passwd entry cache Nov 3 16:21:02.111325 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 3 16:21:02.117513 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Refreshing passwd entry cache Nov 3 16:21:02.118332 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 3 16:21:02.119076 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 3 16:21:02.120242 systemd[1]: Starting update-engine.service - Update Engine... Nov 3 16:21:02.122854 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Failure getting users, quitting Nov 3 16:21:02.122854 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 3 16:21:02.122854 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Refreshing group entry cache Nov 3 16:21:02.121920 oslogin_cache_refresh[1571]: Failure getting users, quitting Nov 3 16:21:02.121938 oslogin_cache_refresh[1571]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 3 16:21:02.121994 oslogin_cache_refresh[1571]: Refreshing group entry cache Nov 3 16:21:02.126002 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 3 16:21:02.130039 extend-filesystems[1570]: Resized partition /dev/vda9 Nov 3 16:21:02.134866 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 3 16:21:02.135646 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Failure getting groups, quitting Nov 3 16:21:02.135646 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 3 16:21:02.133280 oslogin_cache_refresh[1571]: Failure getting groups, quitting Nov 3 16:21:02.133304 oslogin_cache_refresh[1571]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 3 16:21:02.136390 extend-filesystems[1596]: resize2fs 1.47.3 (8-Jul-2025) Nov 3 16:21:02.137702 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 3 16:21:02.146533 jq[1590]: true Nov 3 16:21:02.138025 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 3 16:21:02.138360 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 3 16:21:02.138601 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 3 16:21:02.141608 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 3 16:21:02.141898 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 3 16:21:02.143160 systemd[1]: motdgen.service: Deactivated successfully. Nov 3 16:21:02.143485 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 3 16:21:02.148927 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 1784827 blocks Nov 3 16:21:02.169257 jq[1597]: true Nov 3 16:21:02.171259 update_engine[1587]: I20251103 16:21:02.168821 1587 main.cc:92] Flatcar Update Engine starting Nov 3 16:21:02.180976 kernel: EXT4-fs (vda9): resized filesystem to 1784827 Nov 3 16:21:02.208834 extend-filesystems[1596]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Nov 3 16:21:02.208834 extend-filesystems[1596]: old_desc_blocks = 1, new_desc_blocks = 1 Nov 3 16:21:02.208834 extend-filesystems[1596]: The filesystem on /dev/vda9 is now 1784827 (4k) blocks long. Nov 3 16:21:02.216464 extend-filesystems[1570]: Resized filesystem in /dev/vda9 Nov 3 16:21:02.211408 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 3 16:21:02.211697 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 3 16:21:02.232582 dbus-daemon[1567]: [system] SELinux support is enabled Nov 3 16:21:02.238607 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 3 16:21:02.245079 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). Nov 3 16:21:02.245139 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 3 16:21:02.247713 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). Nov 3 16:21:02.247731 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 3 16:21:02.258119 update_engine[1587]: I20251103 16:21:02.257911 1587 update_check_scheduler.cc:74] Next update check in 6m11s Nov 3 16:21:02.258226 systemd[1]: Started update-engine.service - Update Engine. Nov 3 16:21:02.261814 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 3 16:21:02.278424 systemd-logind[1581]: Watching system buttons on /dev/input/event2 (Power Button) Nov 3 16:21:02.278455 systemd-logind[1581]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 3 16:21:02.278953 systemd-logind[1581]: New seat seat0. Nov 3 16:21:02.280515 systemd[1]: Started systemd-logind.service - User Login Management. Nov 3 16:21:02.320620 bash[1633]: Updated "/home/core/.ssh/authorized_keys" Nov 3 16:21:02.322700 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 3 16:21:02.326398 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 3 16:21:02.397746 locksmithd[1632]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 3 16:21:02.517651 sshd_keygen[1591]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 3 16:21:02.555152 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 3 16:21:02.560249 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 3 16:21:02.585504 systemd[1]: issuegen.service: Deactivated successfully. Nov 3 16:21:02.585819 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 3 16:21:02.589714 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 3 16:21:02.621081 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 3 16:21:02.624970 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 3 16:21:02.628130 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 3 16:21:02.630199 systemd[1]: Reached target getty.target - Login Prompts. Nov 3 16:21:02.648399 containerd[1598]: time="2025-11-03T16:21:02Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 3 16:21:02.649179 containerd[1598]: time="2025-11-03T16:21:02.649139388Z" level=info msg="starting containerd" revision=75cb2b7193e4e490e9fbdc236c0e811ccaba3376 version=v2.1.4 Nov 3 16:21:02.691974 containerd[1598]: time="2025-11-03T16:21:02.691722363Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="18.212µs" Nov 3 16:21:02.691974 containerd[1598]: time="2025-11-03T16:21:02.691887365Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 3 16:21:02.692043 containerd[1598]: time="2025-11-03T16:21:02.692000181Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 3 16:21:02.692190 containerd[1598]: time="2025-11-03T16:21:02.692151318Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 3 16:21:02.692452 containerd[1598]: time="2025-11-03T16:21:02.692393154Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 3 16:21:02.692452 containerd[1598]: time="2025-11-03T16:21:02.692422420Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 3 16:21:02.692599 containerd[1598]: time="2025-11-03T16:21:02.692528508Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 3 16:21:02.692599 containerd[1598]: time="2025-11-03T16:21:02.692545491Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 3 16:21:02.692825 containerd[1598]: time="2025-11-03T16:21:02.692782587Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 3 16:21:02.692825 containerd[1598]: time="2025-11-03T16:21:02.692803574Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 3 16:21:02.692825 containerd[1598]: time="2025-11-03T16:21:02.692820001Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 3 16:21:02.692978 containerd[1598]: time="2025-11-03T16:21:02.692833122Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 3 16:21:02.693152 containerd[1598]: time="2025-11-03T16:21:02.693109599Z" level=info msg="skip loading plugin" error="EROFS unsupported, please `modprobe erofs`: skip plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 3 16:21:02.693152 containerd[1598]: time="2025-11-03T16:21:02.693129333Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 3 16:21:02.693334 containerd[1598]: time="2025-11-03T16:21:02.693295032Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 3 16:21:02.693646 containerd[1598]: time="2025-11-03T16:21:02.693585961Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 3 16:21:02.693701 containerd[1598]: time="2025-11-03T16:21:02.693661796Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 3 16:21:02.693701 containerd[1598]: time="2025-11-03T16:21:02.693680977Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 3 16:21:02.693757 containerd[1598]: time="2025-11-03T16:21:02.693725571Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 3 16:21:02.694257 containerd[1598]: time="2025-11-03T16:21:02.694188803Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 3 16:21:02.694395 containerd[1598]: time="2025-11-03T16:21:02.694363779Z" level=info msg="metadata content store policy set" policy=shared Nov 3 16:21:02.734630 containerd[1598]: time="2025-11-03T16:21:02.734548997Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 3 16:21:02.735303 containerd[1598]: time="2025-11-03T16:21:02.734677595Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 3 16:21:02.735476 containerd[1598]: time="2025-11-03T16:21:02.735442949Z" level=info msg="skip loading plugin" error="could not find mkfs.erofs: exec: \"mkfs.erofs\": executable file not found in $PATH: skip plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 3 16:21:02.735476 containerd[1598]: time="2025-11-03T16:21:02.735461202Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 3 16:21:02.735531 containerd[1598]: time="2025-11-03T16:21:02.735482702Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 3 16:21:02.735531 containerd[1598]: time="2025-11-03T16:21:02.735496932Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 3 16:21:02.735531 containerd[1598]: time="2025-11-03T16:21:02.735511524Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 3 16:21:02.735531 containerd[1598]: time="2025-11-03T16:21:02.735523010Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 3 16:21:02.735600 containerd[1598]: time="2025-11-03T16:21:02.735538097Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 3 16:21:02.735600 containerd[1598]: time="2025-11-03T16:21:02.735562865Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 3 16:21:02.735600 containerd[1598]: time="2025-11-03T16:21:02.735580179Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 3 16:21:02.735600 containerd[1598]: time="2025-11-03T16:21:02.735592301Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 3 16:21:02.735673 containerd[1598]: time="2025-11-03T16:21:02.735602638Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 3 16:21:02.735673 containerd[1598]: time="2025-11-03T16:21:02.735619408Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 3 16:21:02.735810 containerd[1598]: time="2025-11-03T16:21:02.735777886Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 3 16:21:02.735836 containerd[1598]: time="2025-11-03T16:21:02.735814120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 3 16:21:02.735856 containerd[1598]: time="2025-11-03T16:21:02.735837113Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 3 16:21:02.735910 containerd[1598]: time="2025-11-03T16:21:02.735859853Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 3 16:21:02.735910 containerd[1598]: time="2025-11-03T16:21:02.735875191Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 3 16:21:02.735910 containerd[1598]: time="2025-11-03T16:21:02.735905757Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 3 16:21:02.735971 containerd[1598]: time="2025-11-03T16:21:02.735917708Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 3 16:21:02.735971 containerd[1598]: time="2025-11-03T16:21:02.735933490Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 3 16:21:02.735971 containerd[1598]: time="2025-11-03T16:21:02.735957966Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 3 16:21:02.735971 containerd[1598]: time="2025-11-03T16:21:02.735971328Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 3 16:21:02.736042 containerd[1598]: time="2025-11-03T16:21:02.735990226Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 3 16:21:02.736042 containerd[1598]: time="2025-11-03T16:21:02.736023959Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 3 16:21:02.736142 containerd[1598]: time="2025-11-03T16:21:02.736116424Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 3 16:21:02.736142 containerd[1598]: time="2025-11-03T16:21:02.736136592Z" level=info msg="Start snapshots syncer" Nov 3 16:21:02.736187 containerd[1598]: time="2025-11-03T16:21:02.736170224Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 3 16:21:02.736994 containerd[1598]: time="2025-11-03T16:21:02.736832222Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 3 16:21:02.737229 containerd[1598]: time="2025-11-03T16:21:02.737194237Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 3 16:21:02.737332 containerd[1598]: time="2025-11-03T16:21:02.737311449Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 3 16:21:02.737565 containerd[1598]: time="2025-11-03T16:21:02.737527993Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 3 16:21:02.737565 containerd[1598]: time="2025-11-03T16:21:02.737576026Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 3 16:21:02.737664 containerd[1598]: time="2025-11-03T16:21:02.737597173Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 3 16:21:02.737664 containerd[1598]: time="2025-11-03T16:21:02.737622031Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 3 16:21:02.737664 containerd[1598]: time="2025-11-03T16:21:02.737644621Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 3 16:21:02.737728 containerd[1598]: time="2025-11-03T16:21:02.737667533Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 3 16:21:02.737728 containerd[1598]: time="2025-11-03T16:21:02.737683436Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 3 16:21:02.737728 containerd[1598]: time="2025-11-03T16:21:02.737696153Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 3 16:21:02.737728 containerd[1598]: time="2025-11-03T16:21:02.737726003Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 3 16:21:02.737798 containerd[1598]: time="2025-11-03T16:21:02.737772967Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 3 16:21:02.737819 containerd[1598]: time="2025-11-03T16:21:02.737795414Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 3 16:21:02.737819 containerd[1598]: time="2025-11-03T16:21:02.737808161Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 3 16:21:02.737874 containerd[1598]: time="2025-11-03T16:21:02.737833503Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 3 16:21:02.737874 containerd[1598]: time="2025-11-03T16:21:02.737870696Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 3 16:21:02.737932 containerd[1598]: time="2025-11-03T16:21:02.737918597Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 3 16:21:02.737952 containerd[1598]: time="2025-11-03T16:21:02.737932110Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 3 16:21:02.737972 containerd[1598]: time="2025-11-03T16:21:02.737952128Z" level=info msg="runtime interface created" Nov 3 16:21:02.737972 containerd[1598]: time="2025-11-03T16:21:02.737960811Z" level=info msg="created NRI interface" Nov 3 16:21:02.737972 containerd[1598]: time="2025-11-03T16:21:02.737969494Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 3 16:21:02.738052 containerd[1598]: time="2025-11-03T16:21:02.737987373Z" level=info msg="Connect containerd service" Nov 3 16:21:02.738052 containerd[1598]: time="2025-11-03T16:21:02.738018787Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 3 16:21:02.740011 containerd[1598]: time="2025-11-03T16:21:02.739966278Z" 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" Nov 3 16:21:02.881868 containerd[1598]: time="2025-11-03T16:21:02.881723664Z" level=info msg="Start subscribing containerd event" Nov 3 16:21:02.881868 containerd[1598]: time="2025-11-03T16:21:02.881806296Z" level=info msg="Start recovering state" Nov 3 16:21:02.882004 containerd[1598]: time="2025-11-03T16:21:02.881965763Z" level=info msg="Start event monitor" Nov 3 16:21:02.882004 containerd[1598]: time="2025-11-03T16:21:02.881990681Z" level=info msg="Start cni network conf syncer for default" Nov 3 16:21:02.882004 containerd[1598]: time="2025-11-03T16:21:02.882001885Z" level=info msg="Start streaming server" Nov 3 16:21:02.882060 containerd[1598]: time="2025-11-03T16:21:02.882017254Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 3 16:21:02.882060 containerd[1598]: time="2025-11-03T16:21:02.882024656Z" level=info msg="runtime interface starting up..." Nov 3 16:21:02.882060 containerd[1598]: time="2025-11-03T16:21:02.882031595Z" level=info msg="starting plugins..." Nov 3 16:21:02.882060 containerd[1598]: time="2025-11-03T16:21:02.882046065Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 3 16:21:02.882544 containerd[1598]: time="2025-11-03T16:21:02.882518192Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 3 16:21:02.882752 containerd[1598]: time="2025-11-03T16:21:02.882683921Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 3 16:21:02.883612 containerd[1598]: time="2025-11-03T16:21:02.882838425Z" level=info msg="containerd successfully booted in 0.235054s" Nov 3 16:21:02.883041 systemd[1]: Started containerd.service - containerd container runtime. Nov 3 16:21:02.950417 systemd-networkd[1506]: eth0: Gained IPv6LL Nov 3 16:21:02.954038 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 3 16:21:02.956633 systemd[1]: Reached target network-online.target - Network is Online. Nov 3 16:21:02.960115 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 3 16:21:02.963623 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 3 16:21:02.967519 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 3 16:21:03.006147 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 3 16:21:03.008940 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 3 16:21:03.009222 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 3 16:21:03.012246 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 3 16:21:04.250136 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 3 16:21:04.253547 systemd[1]: Started sshd@0-10.0.0.55:22-10.0.0.1:34004.service - OpenSSH per-connection server daemon (10.0.0.1:34004). Nov 3 16:21:04.280240 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 3 16:21:04.283221 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 3 16:21:04.285267 systemd[1]: Startup finished in 3.168s (kernel) + 6.924s (initrd) + 5.453s (userspace) = 15.546s. Nov 3 16:21:04.385513 (kubelet)[1703]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 3 16:21:04.433164 sshd[1701]: Accepted publickey for core from 10.0.0.1 port 34004 ssh2: RSA SHA256:6IgjKsfLloMODYUZWLJOfDFsK2vE75XcxHBEtXf0d48 Nov 3 16:21:04.435145 sshd-session[1701]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 3 16:21:04.442582 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 3 16:21:04.443949 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 3 16:21:04.450145 systemd-logind[1581]: New session 1 of user core. Nov 3 16:21:04.475487 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 3 16:21:04.479199 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 3 16:21:04.495682 (systemd)[1710]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 3 16:21:04.498637 systemd-logind[1581]: New session c1 of user core. Nov 3 16:21:04.734624 systemd[1710]: Queued start job for default target default.target. Nov 3 16:21:04.761731 systemd[1710]: Created slice app.slice - User Application Slice. Nov 3 16:21:04.761760 systemd[1710]: Reached target paths.target - Paths. Nov 3 16:21:04.761805 systemd[1710]: Reached target timers.target - Timers. Nov 3 16:21:04.763587 systemd[1710]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 3 16:21:04.775677 systemd[1710]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 3 16:21:04.775812 systemd[1710]: Reached target sockets.target - Sockets. Nov 3 16:21:04.775857 systemd[1710]: Reached target basic.target - Basic System. Nov 3 16:21:04.775918 systemd[1710]: Reached target default.target - Main User Target. Nov 3 16:21:04.775955 systemd[1710]: Startup finished in 267ms. Nov 3 16:21:04.776577 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 3 16:21:04.778399 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 3 16:21:04.796394 systemd[1]: Started sshd@1-10.0.0.55:22-10.0.0.1:34014.service - OpenSSH per-connection server daemon (10.0.0.1:34014). Nov 3 16:21:04.876187 sshd[1728]: Accepted publickey for core from 10.0.0.1 port 34014 ssh2: RSA SHA256:6IgjKsfLloMODYUZWLJOfDFsK2vE75XcxHBEtXf0d48 Nov 3 16:21:04.877973 sshd-session[1728]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 3 16:21:04.882515 systemd-logind[1581]: New session 2 of user core. Nov 3 16:21:04.894020 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 3 16:21:04.911730 sshd[1732]: Connection closed by 10.0.0.1 port 34014 Nov 3 16:21:04.912049 sshd-session[1728]: pam_unix(sshd:session): session closed for user core Nov 3 16:21:04.924697 systemd[1]: sshd@1-10.0.0.55:22-10.0.0.1:34014.service: Deactivated successfully. Nov 3 16:21:04.926976 systemd[1]: session-2.scope: Deactivated successfully. Nov 3 16:21:04.927776 systemd-logind[1581]: Session 2 logged out. Waiting for processes to exit. Nov 3 16:21:04.931240 systemd[1]: Started sshd@2-10.0.0.55:22-10.0.0.1:34020.service - OpenSSH per-connection server daemon (10.0.0.1:34020). Nov 3 16:21:04.931985 systemd-logind[1581]: Removed session 2. Nov 3 16:21:04.998416 sshd[1738]: Accepted publickey for core from 10.0.0.1 port 34020 ssh2: RSA SHA256:6IgjKsfLloMODYUZWLJOfDFsK2vE75XcxHBEtXf0d48 Nov 3 16:21:04.999760 sshd-session[1738]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 3 16:21:05.004933 systemd-logind[1581]: New session 3 of user core. Nov 3 16:21:05.016049 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 3 16:21:05.028105 sshd[1742]: Connection closed by 10.0.0.1 port 34020 Nov 3 16:21:05.028473 sshd-session[1738]: pam_unix(sshd:session): session closed for user core Nov 3 16:21:05.041746 systemd[1]: sshd@2-10.0.0.55:22-10.0.0.1:34020.service: Deactivated successfully. Nov 3 16:21:05.043951 systemd[1]: session-3.scope: Deactivated successfully. Nov 3 16:21:05.044741 systemd-logind[1581]: Session 3 logged out. Waiting for processes to exit. Nov 3 16:21:05.047698 systemd[1]: Started sshd@3-10.0.0.55:22-10.0.0.1:34022.service - OpenSSH per-connection server daemon (10.0.0.1:34022). Nov 3 16:21:05.048490 systemd-logind[1581]: Removed session 3. Nov 3 16:21:05.053718 kubelet[1703]: E1103 16:21:05.053660 1703 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" Nov 3 16:21:05.058300 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 3 16:21:05.058508 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 3 16:21:05.063312 systemd[1]: kubelet.service: Consumed 1.773s CPU time, 265.9M memory peak. Nov 3 16:21:05.108553 sshd[1748]: Accepted publickey for core from 10.0.0.1 port 34022 ssh2: RSA SHA256:6IgjKsfLloMODYUZWLJOfDFsK2vE75XcxHBEtXf0d48 Nov 3 16:21:05.110081 sshd-session[1748]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 3 16:21:05.115387 systemd-logind[1581]: New session 4 of user core. Nov 3 16:21:05.125023 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 3 16:21:05.308982 sshd[1752]: Connection closed by 10.0.0.1 port 34022 Nov 3 16:21:05.309300 sshd-session[1748]: pam_unix(sshd:session): session closed for user core Nov 3 16:21:05.321196 systemd[1]: sshd@3-10.0.0.55:22-10.0.0.1:34022.service: Deactivated successfully. Nov 3 16:21:05.323453 systemd[1]: session-4.scope: Deactivated successfully. Nov 3 16:21:05.324419 systemd-logind[1581]: Session 4 logged out. Waiting for processes to exit. Nov 3 16:21:05.327782 systemd[1]: Started sshd@4-10.0.0.55:22-10.0.0.1:34032.service - OpenSSH per-connection server daemon (10.0.0.1:34032). Nov 3 16:21:05.328786 systemd-logind[1581]: Removed session 4. Nov 3 16:21:05.390549 sshd[1758]: Accepted publickey for core from 10.0.0.1 port 34032 ssh2: RSA SHA256:6IgjKsfLloMODYUZWLJOfDFsK2vE75XcxHBEtXf0d48 Nov 3 16:21:05.391822 sshd-session[1758]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 3 16:21:05.396501 systemd-logind[1581]: New session 5 of user core. Nov 3 16:21:05.406019 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 3 16:21:05.427575 sudo[1762]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 3 16:21:05.427934 sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 3 16:21:05.443582 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Nov 3 16:21:05.480674 systemd[1]: coreos-metadata.service: Deactivated successfully. Nov 3 16:21:05.481020 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Nov 3 16:21:06.332926 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 3 16:21:06.333104 systemd[1]: kubelet.service: Consumed 1.773s CPU time, 265.9M memory peak. Nov 3 16:21:06.335514 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 3 16:21:06.364088 systemd[1]: Reload requested from client PID 1806 ('systemctl') (unit session-5.scope)... Nov 3 16:21:06.364107 systemd[1]: Reloading... Nov 3 16:21:06.447912 zram_generator::config[1849]: No configuration found. Nov 3 16:21:06.745475 systemd[1]: Reloading finished in 380 ms. Nov 3 16:21:06.813778 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Nov 3 16:21:06.813895 systemd[1]: kubelet.service: Failed with result 'signal'. Nov 3 16:21:06.814219 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 3 16:21:06.814264 systemd[1]: kubelet.service: Consumed 280ms CPU time, 98.4M memory peak. Nov 3 16:21:06.815846 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 3 16:21:07.033681 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 3 16:21:07.039099 (kubelet)[1897]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 3 16:21:07.154792 kubelet[1897]: 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. Nov 3 16:21:07.154792 kubelet[1897]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 3 16:21:07.154792 kubelet[1897]: 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. Nov 3 16:21:07.155134 kubelet[1897]: I1103 16:21:07.154902 1897 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 3 16:21:07.736941 kubelet[1897]: I1103 16:21:07.736905 1897 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Nov 3 16:21:07.736941 kubelet[1897]: I1103 16:21:07.736938 1897 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 3 16:21:07.740510 kubelet[1897]: I1103 16:21:07.740461 1897 server.go:956] "Client rotation is on, will bootstrap in background" Nov 3 16:21:09.121198 kubelet[1897]: I1103 16:21:09.121138 1897 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 3 16:21:09.130232 kubelet[1897]: I1103 16:21:09.130206 1897 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Nov 3 16:21:09.138917 kubelet[1897]: I1103 16:21:09.138458 1897 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 3 16:21:09.138917 kubelet[1897]: I1103 16:21:09.138764 1897 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 3 16:21:09.139108 kubelet[1897]: I1103 16:21:09.138794 1897 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.55","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 3 16:21:09.139108 kubelet[1897]: I1103 16:21:09.139080 1897 topology_manager.go:138] "Creating topology manager with none policy" Nov 3 16:21:09.139108 kubelet[1897]: I1103 16:21:09.139092 1897 container_manager_linux.go:303] "Creating device plugin manager" Nov 3 16:21:09.139603 kubelet[1897]: I1103 16:21:09.139265 1897 state_mem.go:36] "Initialized new in-memory state store" Nov 3 16:21:09.141874 kubelet[1897]: I1103 16:21:09.141815 1897 kubelet.go:480] "Attempting to sync node with API server" Nov 3 16:21:09.141874 kubelet[1897]: I1103 16:21:09.141894 1897 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 3 16:21:09.142166 kubelet[1897]: I1103 16:21:09.142141 1897 kubelet.go:386] "Adding apiserver pod source" Nov 3 16:21:09.143502 kubelet[1897]: I1103 16:21:09.143471 1897 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 3 16:21:09.143549 kubelet[1897]: E1103 16:21:09.143529 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:09.143666 kubelet[1897]: E1103 16:21:09.143626 1897 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:09.147077 kubelet[1897]: I1103 16:21:09.147027 1897 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.1.4" apiVersion="v1" Nov 3 16:21:09.147761 kubelet[1897]: I1103 16:21:09.147728 1897 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Nov 3 16:21:09.148708 kubelet[1897]: W1103 16:21:09.148673 1897 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 3 16:21:09.151642 kubelet[1897]: E1103 16:21:09.151363 1897 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Nov 3 16:21:09.151642 kubelet[1897]: E1103 16:21:09.151618 1897 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.55\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Nov 3 16:21:09.152430 kubelet[1897]: I1103 16:21:09.152397 1897 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 3 16:21:09.152493 kubelet[1897]: I1103 16:21:09.152478 1897 server.go:1289] "Started kubelet" Nov 3 16:21:09.154552 kubelet[1897]: I1103 16:21:09.153636 1897 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 3 16:21:09.154552 kubelet[1897]: I1103 16:21:09.154188 1897 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 3 16:21:09.154552 kubelet[1897]: I1103 16:21:09.154241 1897 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Nov 3 16:21:09.154654 kubelet[1897]: I1103 16:21:09.154636 1897 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 3 16:21:09.155858 kubelet[1897]: I1103 16:21:09.155587 1897 server.go:317] "Adding debug handlers to kubelet server" Nov 3 16:21:09.157442 kubelet[1897]: I1103 16:21:09.157193 1897 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 3 16:21:09.158404 kubelet[1897]: E1103 16:21:09.158307 1897 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 3 16:21:09.158404 kubelet[1897]: E1103 16:21:09.158382 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:09.158464 kubelet[1897]: I1103 16:21:09.158410 1897 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 3 16:21:09.158590 kubelet[1897]: I1103 16:21:09.158570 1897 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 3 16:21:09.158686 kubelet[1897]: I1103 16:21:09.158663 1897 reconciler.go:26] "Reconciler: start to sync state" Nov 3 16:21:09.159370 kubelet[1897]: I1103 16:21:09.159346 1897 factory.go:223] Registration of the systemd container factory successfully Nov 3 16:21:09.159476 kubelet[1897]: I1103 16:21:09.159454 1897 factory.go:221] 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 Nov 3 16:21:09.160784 kubelet[1897]: I1103 16:21:09.160764 1897 factory.go:223] Registration of the containerd container factory successfully Nov 3 16:21:09.168867 kubelet[1897]: E1103 16:21:09.168791 1897 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.55\" not found" node="10.0.0.55" Nov 3 16:21:09.173732 kubelet[1897]: I1103 16:21:09.173491 1897 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 3 16:21:09.173732 kubelet[1897]: I1103 16:21:09.173506 1897 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 3 16:21:09.173732 kubelet[1897]: I1103 16:21:09.173524 1897 state_mem.go:36] "Initialized new in-memory state store" Nov 3 16:21:09.176303 kubelet[1897]: I1103 16:21:09.176266 1897 policy_none.go:49] "None policy: Start" Nov 3 16:21:09.176303 kubelet[1897]: I1103 16:21:09.176292 1897 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 3 16:21:09.176303 kubelet[1897]: I1103 16:21:09.176308 1897 state_mem.go:35] "Initializing new in-memory state store" Nov 3 16:21:09.186716 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 3 16:21:09.197605 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 3 16:21:09.201386 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 3 16:21:09.212008 kubelet[1897]: E1103 16:21:09.211975 1897 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Nov 3 16:21:09.212356 kubelet[1897]: I1103 16:21:09.212278 1897 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 3 16:21:09.212356 kubelet[1897]: I1103 16:21:09.212309 1897 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 3 16:21:09.212989 kubelet[1897]: I1103 16:21:09.212651 1897 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 3 16:21:09.215702 kubelet[1897]: E1103 16:21:09.215672 1897 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 3 16:21:09.215745 kubelet[1897]: E1103 16:21:09.215726 1897 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.55\" not found" Nov 3 16:21:09.232710 kubelet[1897]: I1103 16:21:09.232662 1897 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Nov 3 16:21:09.234112 kubelet[1897]: I1103 16:21:09.234084 1897 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Nov 3 16:21:09.234154 kubelet[1897]: I1103 16:21:09.234123 1897 status_manager.go:230] "Starting to sync pod status with apiserver" Nov 3 16:21:09.234176 kubelet[1897]: I1103 16:21:09.234153 1897 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 3 16:21:09.234176 kubelet[1897]: I1103 16:21:09.234167 1897 kubelet.go:2436] "Starting kubelet main sync loop" Nov 3 16:21:09.234253 kubelet[1897]: E1103 16:21:09.234235 1897 kubelet.go:2460] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Nov 3 16:21:09.313368 kubelet[1897]: I1103 16:21:09.313329 1897 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.55" Nov 3 16:21:09.318452 kubelet[1897]: I1103 16:21:09.318384 1897 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.55" Nov 3 16:21:09.318452 kubelet[1897]: E1103 16:21:09.318423 1897 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.55\": node \"10.0.0.55\" not found" Nov 3 16:21:09.340268 kubelet[1897]: E1103 16:21:09.340216 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:09.441096 kubelet[1897]: E1103 16:21:09.440867 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:09.541544 kubelet[1897]: E1103 16:21:09.541454 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:09.617306 sudo[1762]: pam_unix(sudo:session): session closed for user root Nov 3 16:21:09.619319 sshd[1761]: Connection closed by 10.0.0.1 port 34032 Nov 3 16:21:09.619731 sshd-session[1758]: pam_unix(sshd:session): session closed for user core Nov 3 16:21:09.625750 systemd[1]: sshd@4-10.0.0.55:22-10.0.0.1:34032.service: Deactivated successfully. Nov 3 16:21:09.628597 systemd[1]: session-5.scope: Deactivated successfully. Nov 3 16:21:09.628925 systemd[1]: session-5.scope: Consumed 871ms CPU time, 79.1M memory peak. Nov 3 16:21:09.630787 systemd-logind[1581]: Session 5 logged out. Waiting for processes to exit. Nov 3 16:21:09.632801 systemd-logind[1581]: Removed session 5. Nov 3 16:21:09.642006 kubelet[1897]: E1103 16:21:09.641825 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:09.743639 kubelet[1897]: E1103 16:21:09.743288 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:09.843984 kubelet[1897]: E1103 16:21:09.843837 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:09.944433 kubelet[1897]: E1103 16:21:09.944366 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.045349 kubelet[1897]: E1103 16:21:10.045165 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.108934 kubelet[1897]: I1103 16:21:10.108894 1897 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 3 16:21:10.109269 kubelet[1897]: I1103 16:21:10.109208 1897 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 3 16:21:10.109269 kubelet[1897]: I1103 16:21:10.109208 1897 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 3 16:21:10.144436 kubelet[1897]: E1103 16:21:10.144385 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:10.145579 kubelet[1897]: E1103 16:21:10.145541 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.246040 kubelet[1897]: E1103 16:21:10.246017 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.346895 kubelet[1897]: E1103 16:21:10.346793 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.447615 kubelet[1897]: E1103 16:21:10.447553 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.548331 kubelet[1897]: E1103 16:21:10.548295 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.649249 kubelet[1897]: E1103 16:21:10.649137 1897 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.55\" not found" Nov 3 16:21:10.750275 kubelet[1897]: I1103 16:21:10.750237 1897 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 3 16:21:10.750759 containerd[1598]: time="2025-11-03T16:21:10.750654704Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 3 16:21:10.751305 kubelet[1897]: I1103 16:21:10.750903 1897 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 3 16:21:11.145297 kubelet[1897]: I1103 16:21:11.145164 1897 apiserver.go:52] "Watching apiserver" Nov 3 16:21:11.145297 kubelet[1897]: E1103 16:21:11.145181 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:11.159262 kubelet[1897]: I1103 16:21:11.159239 1897 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 3 16:21:11.163733 systemd[1]: Created slice kubepods-besteffort-podaf458655_0752_46a0_a8e8_1000ad8edff0.slice - libcontainer container kubepods-besteffort-podaf458655_0752_46a0_a8e8_1000ad8edff0.slice. Nov 3 16:21:11.288208 kubelet[1897]: I1103 16:21:11.288112 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/af458655-0752-46a0-a8e8-1000ad8edff0-xtables-lock\") pod \"kube-proxy-w5fjs\" (UID: \"af458655-0752-46a0-a8e8-1000ad8edff0\") " pod="kube-system/kube-proxy-w5fjs" Nov 3 16:21:11.288383 kubelet[1897]: I1103 16:21:11.288207 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s554b\" (UniqueName: \"kubernetes.io/projected/af458655-0752-46a0-a8e8-1000ad8edff0-kube-api-access-s554b\") pod \"kube-proxy-w5fjs\" (UID: \"af458655-0752-46a0-a8e8-1000ad8edff0\") " pod="kube-system/kube-proxy-w5fjs" Nov 3 16:21:11.288383 kubelet[1897]: I1103 16:21:11.288267 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/39fb374a-2aa2-46c5-bb1e-d2d69252a51f-run\") pod \"kube-flannel-ds-7dd7z\" (UID: \"39fb374a-2aa2-46c5-bb1e-d2d69252a51f\") " pod="kube-flannel/kube-flannel-ds-7dd7z" Nov 3 16:21:11.288383 kubelet[1897]: I1103 16:21:11.288287 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-n8h7q\" (UniqueName: \"kubernetes.io/projected/39fb374a-2aa2-46c5-bb1e-d2d69252a51f-kube-api-access-n8h7q\") pod \"kube-flannel-ds-7dd7z\" (UID: \"39fb374a-2aa2-46c5-bb1e-d2d69252a51f\") " pod="kube-flannel/kube-flannel-ds-7dd7z" Nov 3 16:21:11.288383 kubelet[1897]: I1103 16:21:11.288312 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/af458655-0752-46a0-a8e8-1000ad8edff0-lib-modules\") pod \"kube-proxy-w5fjs\" (UID: \"af458655-0752-46a0-a8e8-1000ad8edff0\") " pod="kube-system/kube-proxy-w5fjs" Nov 3 16:21:11.288383 kubelet[1897]: I1103 16:21:11.288329 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/39fb374a-2aa2-46c5-bb1e-d2d69252a51f-cni-plugin\") pod \"kube-flannel-ds-7dd7z\" (UID: \"39fb374a-2aa2-46c5-bb1e-d2d69252a51f\") " pod="kube-flannel/kube-flannel-ds-7dd7z" Nov 3 16:21:11.288584 kubelet[1897]: I1103 16:21:11.288361 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/39fb374a-2aa2-46c5-bb1e-d2d69252a51f-cni\") pod \"kube-flannel-ds-7dd7z\" (UID: \"39fb374a-2aa2-46c5-bb1e-d2d69252a51f\") " pod="kube-flannel/kube-flannel-ds-7dd7z" Nov 3 16:21:11.288584 kubelet[1897]: I1103 16:21:11.288401 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/39fb374a-2aa2-46c5-bb1e-d2d69252a51f-flannel-cfg\") pod \"kube-flannel-ds-7dd7z\" (UID: \"39fb374a-2aa2-46c5-bb1e-d2d69252a51f\") " pod="kube-flannel/kube-flannel-ds-7dd7z" Nov 3 16:21:11.288584 kubelet[1897]: I1103 16:21:11.288423 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/39fb374a-2aa2-46c5-bb1e-d2d69252a51f-xtables-lock\") pod \"kube-flannel-ds-7dd7z\" (UID: \"39fb374a-2aa2-46c5-bb1e-d2d69252a51f\") " pod="kube-flannel/kube-flannel-ds-7dd7z" Nov 3 16:21:11.288584 kubelet[1897]: I1103 16:21:11.288440 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/af458655-0752-46a0-a8e8-1000ad8edff0-kube-proxy\") pod \"kube-proxy-w5fjs\" (UID: \"af458655-0752-46a0-a8e8-1000ad8edff0\") " pod="kube-system/kube-proxy-w5fjs" Nov 3 16:21:11.296827 systemd[1]: Created slice kubepods-burstable-pod39fb374a_2aa2_46c5_bb1e_d2d69252a51f.slice - libcontainer container kubepods-burstable-pod39fb374a_2aa2_46c5_bb1e_d2d69252a51f.slice. Nov 3 16:21:11.595143 kubelet[1897]: E1103 16:21:11.594933 1897 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" Nov 3 16:21:11.595904 containerd[1598]: time="2025-11-03T16:21:11.595783985Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-w5fjs,Uid:af458655-0752-46a0-a8e8-1000ad8edff0,Namespace:kube-system,Attempt:0,}" Nov 3 16:21:11.607475 kubelet[1897]: E1103 16:21:11.607434 1897 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" Nov 3 16:21:11.607847 containerd[1598]: time="2025-11-03T16:21:11.607810260Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7dd7z,Uid:39fb374a-2aa2-46c5-bb1e-d2d69252a51f,Namespace:kube-flannel,Attempt:0,}" Nov 3 16:21:12.146217 kubelet[1897]: E1103 16:21:12.146146 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:12.480543 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3235125236.mount: Deactivated successfully. Nov 3 16:21:12.489049 containerd[1598]: time="2025-11-03T16:21:12.488993002Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 3 16:21:12.492123 containerd[1598]: time="2025-11-03T16:21:12.492076949Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Nov 3 16:21:12.493834 containerd[1598]: time="2025-11-03T16:21:12.493802672Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 3 16:21:12.495074 containerd[1598]: time="2025-11-03T16:21:12.495036789Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 3 16:21:12.495748 containerd[1598]: time="2025-11-03T16:21:12.495718018Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Nov 3 16:21:12.496583 containerd[1598]: time="2025-11-03T16:21:12.496547040Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 3 16:21:12.497267 containerd[1598]: time="2025-11-03T16:21:12.497227566Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 848.678001ms" Nov 3 16:21:12.499291 containerd[1598]: time="2025-11-03T16:21:12.499021207Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 848.716229ms" Nov 3 16:21:12.528417 containerd[1598]: time="2025-11-03T16:21:12.528359358Z" level=info msg="connecting to shim 5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d" address="unix:///run/containerd/s/61482041ee2963d33f0b7168e7bf79f7c0ce6e28f87987b03ea9496f6e481511" namespace=k8s.io protocol=ttrpc version=3 Nov 3 16:21:12.567696 containerd[1598]: time="2025-11-03T16:21:12.567622830Z" level=info msg="connecting to shim 6a53b524cf94c056862719ead01eb4faab482bdae13fa8fc856f9df5c837cc43" address="unix:///run/containerd/s/c90fee6643046346671abead8e142f9644bc93bfb3ece90b48133c6f0eaac825" namespace=k8s.io protocol=ttrpc version=3 Nov 3 16:21:12.588081 systemd[1]: Started cri-containerd-5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d.scope - libcontainer container 5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d. Nov 3 16:21:12.598989 systemd[1]: Started cri-containerd-6a53b524cf94c056862719ead01eb4faab482bdae13fa8fc856f9df5c837cc43.scope - libcontainer container 6a53b524cf94c056862719ead01eb4faab482bdae13fa8fc856f9df5c837cc43. Nov 3 16:21:12.637915 containerd[1598]: time="2025-11-03T16:21:12.637829335Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-w5fjs,Uid:af458655-0752-46a0-a8e8-1000ad8edff0,Namespace:kube-system,Attempt:0,} returns sandbox id \"6a53b524cf94c056862719ead01eb4faab482bdae13fa8fc856f9df5c837cc43\"" Nov 3 16:21:12.639308 kubelet[1897]: E1103 16:21:12.638786 1897 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" Nov 3 16:21:12.640100 containerd[1598]: time="2025-11-03T16:21:12.640070598Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Nov 3 16:21:12.648003 containerd[1598]: time="2025-11-03T16:21:12.647945184Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7dd7z,Uid:39fb374a-2aa2-46c5-bb1e-d2d69252a51f,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d\"" Nov 3 16:21:12.648620 kubelet[1897]: E1103 16:21:12.648571 1897 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" Nov 3 16:21:13.146362 kubelet[1897]: E1103 16:21:13.146279 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:14.060943 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1745272526.mount: Deactivated successfully. Nov 3 16:21:14.146644 kubelet[1897]: E1103 16:21:14.146579 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:14.934865 containerd[1598]: time="2025-11-03T16:21:14.934772742Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:14.935771 containerd[1598]: time="2025-11-03T16:21:14.935746749Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=31925747" Nov 3 16:21:14.937048 containerd[1598]: time="2025-11-03T16:21:14.936977651Z" level=info msg="ImageCreate event name:\"sha256:2844ee7bb56c2c194e1f4adafb9e7b60b9ed16aa4d07ab8ad1f019362e2efab3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:14.939054 containerd[1598]: time="2025-11-03T16:21:14.939003748Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:14.939757 containerd[1598]: time="2025-11-03T16:21:14.939710470Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:2844ee7bb56c2c194e1f4adafb9e7b60b9ed16aa4d07ab8ad1f019362e2efab3\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"31928488\" in 2.299603312s" Nov 3 16:21:14.939757 containerd[1598]: time="2025-11-03T16:21:14.939743209Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:2844ee7bb56c2c194e1f4adafb9e7b60b9ed16aa4d07ab8ad1f019362e2efab3\"" Nov 3 16:21:14.941104 containerd[1598]: time="2025-11-03T16:21:14.940908080Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Nov 3 16:21:14.946576 containerd[1598]: time="2025-11-03T16:21:14.946524321Z" level=info msg="CreateContainer within sandbox \"6a53b524cf94c056862719ead01eb4faab482bdae13fa8fc856f9df5c837cc43\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 3 16:21:14.961616 containerd[1598]: time="2025-11-03T16:21:14.961544505Z" level=info msg="Container 3d49d8e2139549b539bd57bd967d3dd8803334c2e363c9544157aac86a7dabd5: CDI devices from CRI Config.CDIDevices: []" Nov 3 16:21:14.975601 containerd[1598]: time="2025-11-03T16:21:14.975522419Z" level=info msg="CreateContainer within sandbox \"6a53b524cf94c056862719ead01eb4faab482bdae13fa8fc856f9df5c837cc43\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"3d49d8e2139549b539bd57bd967d3dd8803334c2e363c9544157aac86a7dabd5\"" Nov 3 16:21:14.976474 containerd[1598]: time="2025-11-03T16:21:14.976408849Z" level=info msg="StartContainer for \"3d49d8e2139549b539bd57bd967d3dd8803334c2e363c9544157aac86a7dabd5\"" Nov 3 16:21:14.978501 containerd[1598]: time="2025-11-03T16:21:14.978464519Z" level=info msg="connecting to shim 3d49d8e2139549b539bd57bd967d3dd8803334c2e363c9544157aac86a7dabd5" address="unix:///run/containerd/s/c90fee6643046346671abead8e142f9644bc93bfb3ece90b48133c6f0eaac825" protocol=ttrpc version=3 Nov 3 16:21:15.026220 systemd[1]: Started cri-containerd-3d49d8e2139549b539bd57bd967d3dd8803334c2e363c9544157aac86a7dabd5.scope - libcontainer container 3d49d8e2139549b539bd57bd967d3dd8803334c2e363c9544157aac86a7dabd5. Nov 3 16:21:15.099723 containerd[1598]: time="2025-11-03T16:21:15.099656010Z" level=info msg="StartContainer for \"3d49d8e2139549b539bd57bd967d3dd8803334c2e363c9544157aac86a7dabd5\" returns successfully" Nov 3 16:21:15.147558 kubelet[1897]: E1103 16:21:15.147501 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:15.301287 kubelet[1897]: E1103 16:21:15.301112 1897 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" Nov 3 16:21:16.148409 kubelet[1897]: E1103 16:21:16.148334 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:16.303020 kubelet[1897]: E1103 16:21:16.302984 1897 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" Nov 3 16:21:16.374552 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1798582956.mount: Deactivated successfully. Nov 3 16:21:16.425055 containerd[1598]: time="2025-11-03T16:21:16.424847493Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:16.426116 containerd[1598]: time="2025-11-03T16:21:16.426049195Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=0" Nov 3 16:21:16.427717 containerd[1598]: time="2025-11-03T16:21:16.427663323Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:16.430686 containerd[1598]: time="2025-11-03T16:21:16.430638142Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:16.431718 containerd[1598]: time="2025-11-03T16:21:16.431666079Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 1.49071441s" Nov 3 16:21:16.431718 containerd[1598]: time="2025-11-03T16:21:16.431704363Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Nov 3 16:21:16.439964 containerd[1598]: time="2025-11-03T16:21:16.439902485Z" level=info msg="CreateContainer within sandbox \"5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 3 16:21:16.449856 containerd[1598]: time="2025-11-03T16:21:16.449812407Z" level=info msg="Container 606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418: CDI devices from CRI Config.CDIDevices: []" Nov 3 16:21:16.458157 containerd[1598]: time="2025-11-03T16:21:16.458092742Z" level=info msg="CreateContainer within sandbox \"5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418\"" Nov 3 16:21:16.458813 containerd[1598]: time="2025-11-03T16:21:16.458775358Z" level=info msg="StartContainer for \"606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418\"" Nov 3 16:21:16.459944 containerd[1598]: time="2025-11-03T16:21:16.459867697Z" level=info msg="connecting to shim 606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418" address="unix:///run/containerd/s/61482041ee2963d33f0b7168e7bf79f7c0ce6e28f87987b03ea9496f6e481511" protocol=ttrpc version=3 Nov 3 16:21:16.511050 systemd[1]: Started cri-containerd-606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418.scope - libcontainer container 606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418. Nov 3 16:21:16.554485 containerd[1598]: time="2025-11-03T16:21:16.554442443Z" level=info msg="StartContainer for \"606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418\" returns successfully" Nov 3 16:21:16.568980 systemd[1]: cri-containerd-606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418.scope: Deactivated successfully. Nov 3 16:21:16.570826 containerd[1598]: time="2025-11-03T16:21:16.570773584Z" level=info msg="received exit event container_id:\"606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418\" id:\"606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418\" pid:2240 exited_at:{seconds:1762186876 nanos:570217199}" Nov 3 16:21:17.148587 kubelet[1897]: E1103 16:21:17.148490 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:17.281793 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-606f24abcb5c121722cb2a0f2a8f39ca8e2f6357ea60a9a42d94a7cda8de4418-rootfs.mount: Deactivated successfully. Nov 3 16:21:17.306897 kubelet[1897]: E1103 16:21:17.306837 1897 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" Nov 3 16:21:17.307690 containerd[1598]: time="2025-11-03T16:21:17.307640496Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Nov 3 16:21:17.317701 kubelet[1897]: I1103 16:21:17.317614 1897 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-w5fjs" podStartSLOduration=6.016553578 podStartE2EDuration="8.317591844s" podCreationTimestamp="2025-11-03 16:21:09 +0000 UTC" firstStartedPulling="2025-11-03 16:21:12.639699984 +0000 UTC m=+5.595697289" lastFinishedPulling="2025-11-03 16:21:14.940738249 +0000 UTC m=+7.896735555" observedRunningTime="2025-11-03 16:21:15.310255177 +0000 UTC m=+8.266252512" watchObservedRunningTime="2025-11-03 16:21:17.317591844 +0000 UTC m=+10.273589139" Nov 3 16:21:18.149505 kubelet[1897]: E1103 16:21:18.149438 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:19.150361 kubelet[1897]: E1103 16:21:19.150293 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:19.526156 containerd[1598]: time="2025-11-03T16:21:19.526018684Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:19.526798 containerd[1598]: time="2025-11-03T16:21:19.526757451Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=6764724" Nov 3 16:21:19.528169 containerd[1598]: time="2025-11-03T16:21:19.528126765Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:19.531016 containerd[1598]: time="2025-11-03T16:21:19.530987108Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:19.533162 containerd[1598]: time="2025-11-03T16:21:19.533114567Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32996046\" in 2.225425523s" Nov 3 16:21:19.533162 containerd[1598]: time="2025-11-03T16:21:19.533160171Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Nov 3 16:21:19.537315 containerd[1598]: time="2025-11-03T16:21:19.537270484Z" level=info msg="CreateContainer within sandbox \"5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 3 16:21:19.545117 containerd[1598]: time="2025-11-03T16:21:19.545081627Z" level=info msg="Container e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d: CDI devices from CRI Config.CDIDevices: []" Nov 3 16:21:19.554439 containerd[1598]: time="2025-11-03T16:21:19.554391922Z" level=info msg="CreateContainer within sandbox \"5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d\"" Nov 3 16:21:19.554888 containerd[1598]: time="2025-11-03T16:21:19.554828503Z" level=info msg="StartContainer for \"e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d\"" Nov 3 16:21:19.555659 containerd[1598]: time="2025-11-03T16:21:19.555629743Z" level=info msg="connecting to shim e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d" address="unix:///run/containerd/s/61482041ee2963d33f0b7168e7bf79f7c0ce6e28f87987b03ea9496f6e481511" protocol=ttrpc version=3 Nov 3 16:21:19.718031 systemd[1]: Started cri-containerd-e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d.scope - libcontainer container e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d. Nov 3 16:21:19.756217 systemd[1]: cri-containerd-e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d.scope: Deactivated successfully. Nov 3 16:21:19.758581 containerd[1598]: time="2025-11-03T16:21:19.758528701Z" level=info msg="received exit event container_id:\"e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d\" id:\"e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d\" pid:2312 exited_at:{seconds:1762186879 nanos:756572888}" Nov 3 16:21:19.759984 containerd[1598]: time="2025-11-03T16:21:19.759935265Z" level=info msg="StartContainer for \"e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d\" returns successfully" Nov 3 16:21:19.769185 kubelet[1897]: I1103 16:21:19.769154 1897 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 3 16:21:19.783579 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-e9f03fb7184b972cbca2373340488eef8efbedfd61614c095323a7feb0f6ba5d-rootfs.mount: Deactivated successfully. Nov 3 16:21:20.151312 kubelet[1897]: E1103 16:21:20.151092 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:20.314788 kubelet[1897]: E1103 16:21:20.314673 1897 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" Nov 3 16:21:20.319704 containerd[1598]: time="2025-11-03T16:21:20.319630404Z" level=info msg="CreateContainer within sandbox \"5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 3 16:21:20.329708 containerd[1598]: time="2025-11-03T16:21:20.329668314Z" level=info msg="Container 36eefd7e7ba365282c825e305a84026fb7da46b444e146743ba91f14b8c9a5d2: CDI devices from CRI Config.CDIDevices: []" Nov 3 16:21:20.337318 containerd[1598]: time="2025-11-03T16:21:20.337273919Z" level=info msg="CreateContainer within sandbox \"5795cd5a4c14fb9c3ed99ef59427a0767677e205e9ba4787ec5fceaf1ca9d22d\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"36eefd7e7ba365282c825e305a84026fb7da46b444e146743ba91f14b8c9a5d2\"" Nov 3 16:21:20.337727 containerd[1598]: time="2025-11-03T16:21:20.337705240Z" level=info msg="StartContainer for \"36eefd7e7ba365282c825e305a84026fb7da46b444e146743ba91f14b8c9a5d2\"" Nov 3 16:21:20.338507 containerd[1598]: time="2025-11-03T16:21:20.338474867Z" level=info msg="connecting to shim 36eefd7e7ba365282c825e305a84026fb7da46b444e146743ba91f14b8c9a5d2" address="unix:///run/containerd/s/61482041ee2963d33f0b7168e7bf79f7c0ce6e28f87987b03ea9496f6e481511" protocol=ttrpc version=3 Nov 3 16:21:20.372035 systemd[1]: Started cri-containerd-36eefd7e7ba365282c825e305a84026fb7da46b444e146743ba91f14b8c9a5d2.scope - libcontainer container 36eefd7e7ba365282c825e305a84026fb7da46b444e146743ba91f14b8c9a5d2. Nov 3 16:21:20.411652 containerd[1598]: time="2025-11-03T16:21:20.411467278Z" level=info msg="StartContainer for \"36eefd7e7ba365282c825e305a84026fb7da46b444e146743ba91f14b8c9a5d2\" returns successfully" Nov 3 16:21:20.457052 systemd[1]: Created slice kubepods-besteffort-pod9803c08e_399c_43b4_b60b_15a389dd7ee4.slice - libcontainer container kubepods-besteffort-pod9803c08e_399c_43b4_b60b_15a389dd7ee4.slice. Nov 3 16:21:20.592321 kubelet[1897]: I1103 16:21:20.592252 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-b7x4q\" (UniqueName: \"kubernetes.io/projected/9803c08e-399c-43b4-b60b-15a389dd7ee4-kube-api-access-b7x4q\") pod \"nginx-deployment-7fcdb87857-7fpzg\" (UID: \"9803c08e-399c-43b4-b60b-15a389dd7ee4\") " pod="default/nginx-deployment-7fcdb87857-7fpzg" Nov 3 16:21:20.761765 containerd[1598]: time="2025-11-03T16:21:20.761632960Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-7fpzg,Uid:9803c08e-399c-43b4-b60b-15a389dd7ee4,Namespace:default,Attempt:0,}" Nov 3 16:21:20.795807 systemd[1]: run-netns-cni\x2dc2b8fa9a\x2d033e\x2d5b03\x2dc931\x2d1fadde756665.mount: Deactivated successfully. Nov 3 16:21:20.797519 containerd[1598]: time="2025-11-03T16:21:20.797425131Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-7fpzg,Uid:9803c08e-399c-43b4-b60b-15a389dd7ee4,Namespace:default,Attempt:0,} failed, error" error="rpc error: code = Unknown desc = failed to setup network for sandbox \"30ef8451585033d0e939f5a13bd90c5aaef4596fe45412ca59292470c1d845c2\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 3 16:21:20.797771 kubelet[1897]: E1103 16:21:20.797708 1897 log.go:32] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"30ef8451585033d0e939f5a13bd90c5aaef4596fe45412ca59292470c1d845c2\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" Nov 3 16:21:20.797843 kubelet[1897]: E1103 16:21:20.797797 1897 kuberuntime_sandbox.go:70] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"30ef8451585033d0e939f5a13bd90c5aaef4596fe45412ca59292470c1d845c2\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-7fcdb87857-7fpzg" Nov 3 16:21:20.797843 kubelet[1897]: E1103 16:21:20.797826 1897 kuberuntime_manager.go:1252] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"30ef8451585033d0e939f5a13bd90c5aaef4596fe45412ca59292470c1d845c2\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-7fcdb87857-7fpzg" Nov 3 16:21:20.797965 kubelet[1897]: E1103 16:21:20.797920 1897 pod_workers.go:1301] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-7fcdb87857-7fpzg_default(9803c08e-399c-43b4-b60b-15a389dd7ee4)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-7fcdb87857-7fpzg_default(9803c08e-399c-43b4-b60b-15a389dd7ee4)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"30ef8451585033d0e939f5a13bd90c5aaef4596fe45412ca59292470c1d845c2\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-7fcdb87857-7fpzg" podUID="9803c08e-399c-43b4-b60b-15a389dd7ee4" Nov 3 16:21:21.151826 kubelet[1897]: E1103 16:21:21.151579 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:21.318904 kubelet[1897]: E1103 16:21:21.318829 1897 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" Nov 3 16:21:21.328073 kubelet[1897]: I1103 16:21:21.327997 1897 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7dd7z" podStartSLOduration=5.443350521 podStartE2EDuration="12.327977408s" podCreationTimestamp="2025-11-03 16:21:09 +0000 UTC" firstStartedPulling="2025-11-03 16:21:12.649100455 +0000 UTC m=+5.605097760" lastFinishedPulling="2025-11-03 16:21:19.533727342 +0000 UTC m=+12.489724647" observedRunningTime="2025-11-03 16:21:21.32786192 +0000 UTC m=+14.283859225" watchObservedRunningTime="2025-11-03 16:21:21.327977408 +0000 UTC m=+14.283974713" Nov 3 16:21:21.477216 systemd-networkd[1506]: flannel.1: Link UP Nov 3 16:21:21.477227 systemd-networkd[1506]: flannel.1: Gained carrier Nov 3 16:21:22.152348 kubelet[1897]: E1103 16:21:22.152283 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:22.320602 kubelet[1897]: E1103 16:21:22.320539 1897 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" Nov 3 16:21:23.152542 kubelet[1897]: E1103 16:21:23.152463 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:23.238150 systemd-networkd[1506]: flannel.1: Gained IPv6LL Nov 3 16:21:24.153657 kubelet[1897]: E1103 16:21:24.153559 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:25.154252 kubelet[1897]: E1103 16:21:25.154162 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:26.155027 kubelet[1897]: E1103 16:21:26.154937 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:27.155703 kubelet[1897]: E1103 16:21:27.155625 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:28.156179 kubelet[1897]: E1103 16:21:28.156090 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:29.142958 kubelet[1897]: E1103 16:21:29.142827 1897 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:29.157390 kubelet[1897]: E1103 16:21:29.157272 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:30.157921 kubelet[1897]: E1103 16:21:30.157796 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:31.159091 kubelet[1897]: E1103 16:21:31.158993 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:31.235562 containerd[1598]: time="2025-11-03T16:21:31.235485424Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-7fpzg,Uid:9803c08e-399c-43b4-b60b-15a389dd7ee4,Namespace:default,Attempt:0,}" Nov 3 16:21:31.258824 systemd-networkd[1506]: cni0: Link UP Nov 3 16:21:31.258838 systemd-networkd[1506]: cni0: Gained carrier Nov 3 16:21:31.265028 systemd-networkd[1506]: cni0: Lost carrier Nov 3 16:21:31.272065 systemd-networkd[1506]: vethf626de81: Link UP Nov 3 16:21:31.274994 kernel: cni0: port 1(vethf626de81) entered blocking state Nov 3 16:21:31.275142 kernel: cni0: port 1(vethf626de81) entered disabled state Nov 3 16:21:31.276379 kernel: vethf626de81: entered allmulticast mode Nov 3 16:21:31.277753 kernel: vethf626de81: entered promiscuous mode Nov 3 16:21:31.288328 kernel: cni0: port 1(vethf626de81) entered blocking state Nov 3 16:21:31.288428 kernel: cni0: port 1(vethf626de81) entered forwarding state Nov 3 16:21:31.288487 systemd-networkd[1506]: vethf626de81: Gained carrier Nov 3 16:21:31.289148 systemd-networkd[1506]: cni0: Gained carrier Nov 3 16:21:31.292230 containerd[1598]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Nov 3 16:21:31.292230 containerd[1598]: delegateAdd: netconf sent to delegate plugin: Nov 3 16:21:31.546117 containerd[1598]: {"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-11-03T16:21:31.545951135Z" level=info msg="connecting to shim 55d6bd6d0e2ddd5eb5ab214bcd73a2722551fc2e8b52287e8358a50946481ab7" address="unix:///run/containerd/s/ae7103c5cd4b1447d3839b6a9602f4110d3c27ffdb8276853badf993d31ccc62" namespace=k8s.io protocol=ttrpc version=3 Nov 3 16:21:31.624102 systemd[1]: Started cri-containerd-55d6bd6d0e2ddd5eb5ab214bcd73a2722551fc2e8b52287e8358a50946481ab7.scope - libcontainer container 55d6bd6d0e2ddd5eb5ab214bcd73a2722551fc2e8b52287e8358a50946481ab7. Nov 3 16:21:31.642395 systemd-resolved[1300]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 3 16:21:31.680611 containerd[1598]: time="2025-11-03T16:21:31.680556414Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-7fpzg,Uid:9803c08e-399c-43b4-b60b-15a389dd7ee4,Namespace:default,Attempt:0,} returns sandbox id \"55d6bd6d0e2ddd5eb5ab214bcd73a2722551fc2e8b52287e8358a50946481ab7\"" Nov 3 16:21:31.681795 containerd[1598]: time="2025-11-03T16:21:31.681681025Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 3 16:21:32.159900 kubelet[1897]: E1103 16:21:32.159844 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:32.582201 systemd-networkd[1506]: vethf626de81: Gained IPv6LL Nov 3 16:21:32.774261 systemd-networkd[1506]: cni0: Gained IPv6LL Nov 3 16:21:33.160898 kubelet[1897]: E1103 16:21:33.160817 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:34.161575 kubelet[1897]: E1103 16:21:34.161502 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:34.665968 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1830736195.mount: Deactivated successfully. Nov 3 16:21:35.162806 kubelet[1897]: E1103 16:21:35.162584 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:36.163325 kubelet[1897]: E1103 16:21:36.163265 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:36.322469 containerd[1598]: time="2025-11-03T16:21:36.322384637Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:36.323199 containerd[1598]: time="2025-11-03T16:21:36.323144656Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73297045" Nov 3 16:21:36.324297 containerd[1598]: time="2025-11-03T16:21:36.324249112Z" level=info msg="ImageCreate event name:\"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:36.326899 containerd[1598]: time="2025-11-03T16:21:36.326831357Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:36.327769 containerd[1598]: time="2025-11-03T16:21:36.327725415Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"73311824\" in 4.645975475s" Nov 3 16:21:36.327769 containerd[1598]: time="2025-11-03T16:21:36.327756285Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\"" Nov 3 16:21:36.334217 containerd[1598]: time="2025-11-03T16:21:36.334130615Z" level=info msg="CreateContainer within sandbox \"55d6bd6d0e2ddd5eb5ab214bcd73a2722551fc2e8b52287e8358a50946481ab7\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 3 16:21:36.343852 containerd[1598]: time="2025-11-03T16:21:36.343783433Z" level=info msg="Container 28118161aaedafd499b7b47e37495d755fd0e6b8831e6da0806509b963fdfb8f: CDI devices from CRI Config.CDIDevices: []" Nov 3 16:21:36.351945 containerd[1598]: time="2025-11-03T16:21:36.351852737Z" level=info msg="CreateContainer within sandbox \"55d6bd6d0e2ddd5eb5ab214bcd73a2722551fc2e8b52287e8358a50946481ab7\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"28118161aaedafd499b7b47e37495d755fd0e6b8831e6da0806509b963fdfb8f\"" Nov 3 16:21:36.352626 containerd[1598]: time="2025-11-03T16:21:36.352556450Z" level=info msg="StartContainer for \"28118161aaedafd499b7b47e37495d755fd0e6b8831e6da0806509b963fdfb8f\"" Nov 3 16:21:36.354585 containerd[1598]: time="2025-11-03T16:21:36.354526329Z" level=info msg="connecting to shim 28118161aaedafd499b7b47e37495d755fd0e6b8831e6da0806509b963fdfb8f" address="unix:///run/containerd/s/ae7103c5cd4b1447d3839b6a9602f4110d3c27ffdb8276853badf993d31ccc62" protocol=ttrpc version=3 Nov 3 16:21:36.423142 systemd[1]: Started cri-containerd-28118161aaedafd499b7b47e37495d755fd0e6b8831e6da0806509b963fdfb8f.scope - libcontainer container 28118161aaedafd499b7b47e37495d755fd0e6b8831e6da0806509b963fdfb8f. Nov 3 16:21:36.462804 containerd[1598]: time="2025-11-03T16:21:36.462746024Z" level=info msg="StartContainer for \"28118161aaedafd499b7b47e37495d755fd0e6b8831e6da0806509b963fdfb8f\" returns successfully" Nov 3 16:21:37.164559 kubelet[1897]: E1103 16:21:37.164403 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:37.543857 kubelet[1897]: I1103 16:21:37.543635 1897 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-7fpzg" podStartSLOduration=12.896292055 podStartE2EDuration="17.543607289s" podCreationTimestamp="2025-11-03 16:21:20 +0000 UTC" firstStartedPulling="2025-11-03 16:21:31.681301492 +0000 UTC m=+24.637298797" lastFinishedPulling="2025-11-03 16:21:36.328616726 +0000 UTC m=+29.284614031" observedRunningTime="2025-11-03 16:21:37.543456885 +0000 UTC m=+30.499454190" watchObservedRunningTime="2025-11-03 16:21:37.543607289 +0000 UTC m=+30.499604594" Nov 3 16:21:38.164762 kubelet[1897]: E1103 16:21:38.164683 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:39.165397 kubelet[1897]: E1103 16:21:39.165333 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:40.165901 kubelet[1897]: E1103 16:21:40.165831 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:41.166642 kubelet[1897]: E1103 16:21:41.166577 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:42.167670 kubelet[1897]: E1103 16:21:42.167584 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:42.745866 systemd[1]: Created slice kubepods-besteffort-pod13cf3a64_754e_40c0_ba4e_099c2e0e714c.slice - libcontainer container kubepods-besteffort-pod13cf3a64_754e_40c0_ba4e_099c2e0e714c.slice. Nov 3 16:21:42.747081 kubelet[1897]: I1103 16:21:42.747053 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/13cf3a64-754e-40c0-ba4e-099c2e0e714c-data\") pod \"nfs-server-provisioner-0\" (UID: \"13cf3a64-754e-40c0-ba4e-099c2e0e714c\") " pod="default/nfs-server-provisioner-0" Nov 3 16:21:42.747081 kubelet[1897]: I1103 16:21:42.747086 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-v8p2p\" (UniqueName: \"kubernetes.io/projected/13cf3a64-754e-40c0-ba4e-099c2e0e714c-kube-api-access-v8p2p\") pod \"nfs-server-provisioner-0\" (UID: \"13cf3a64-754e-40c0-ba4e-099c2e0e714c\") " pod="default/nfs-server-provisioner-0" Nov 3 16:21:43.051476 containerd[1598]: time="2025-11-03T16:21:43.051272572Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:13cf3a64-754e-40c0-ba4e-099c2e0e714c,Namespace:default,Attempt:0,}" Nov 3 16:21:43.068596 systemd-networkd[1506]: veth9d57e8c7: Link UP Nov 3 16:21:43.071334 kernel: cni0: port 2(veth9d57e8c7) entered blocking state Nov 3 16:21:43.071392 kernel: cni0: port 2(veth9d57e8c7) entered disabled state Nov 3 16:21:43.071410 kernel: veth9d57e8c7: entered allmulticast mode Nov 3 16:21:43.073743 kernel: veth9d57e8c7: entered promiscuous mode Nov 3 16:21:43.082456 kernel: cni0: port 2(veth9d57e8c7) entered blocking state Nov 3 16:21:43.082551 kernel: cni0: port 2(veth9d57e8c7) entered forwarding state Nov 3 16:21:43.082648 systemd-networkd[1506]: veth9d57e8c7: Gained carrier Nov 3 16:21:43.086042 containerd[1598]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Nov 3 16:21:43.086042 containerd[1598]: delegateAdd: netconf sent to delegate plugin: Nov 3 16:21:43.117710 containerd[1598]: {"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-11-03T16:21:43.117653326Z" level=info msg="connecting to shim f15c3c8acb8e273183f1b5f3cfa0083f1fad54e005b8d1fa238bebe492647f12" address="unix:///run/containerd/s/d8b8a17490754ba09723caf9e427bed6b3c41cb9bd7b9edb479f94afc68b472c" namespace=k8s.io protocol=ttrpc version=3 Nov 3 16:21:43.155063 systemd[1]: Started cri-containerd-f15c3c8acb8e273183f1b5f3cfa0083f1fad54e005b8d1fa238bebe492647f12.scope - libcontainer container f15c3c8acb8e273183f1b5f3cfa0083f1fad54e005b8d1fa238bebe492647f12. Nov 3 16:21:43.167979 systemd-resolved[1300]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 3 16:21:43.168401 kubelet[1897]: E1103 16:21:43.168287 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:43.198270 containerd[1598]: time="2025-11-03T16:21:43.198206660Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:13cf3a64-754e-40c0-ba4e-099c2e0e714c,Namespace:default,Attempt:0,} returns sandbox id \"f15c3c8acb8e273183f1b5f3cfa0083f1fad54e005b8d1fa238bebe492647f12\"" Nov 3 16:21:43.200223 containerd[1598]: time="2025-11-03T16:21:43.200158446Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 3 16:21:44.168550 kubelet[1897]: E1103 16:21:44.168468 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:45.062110 systemd-networkd[1506]: veth9d57e8c7: Gained IPv6LL Nov 3 16:21:45.169487 kubelet[1897]: E1103 16:21:45.169434 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:45.300496 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4189786990.mount: Deactivated successfully. Nov 3 16:21:46.170040 kubelet[1897]: E1103 16:21:46.169969 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:47.171014 kubelet[1897]: E1103 16:21:47.170935 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:47.743635 update_engine[1587]: I20251103 16:21:47.743525 1587 update_attempter.cc:509] Updating boot flags... Nov 3 16:21:48.092895 containerd[1598]: time="2025-11-03T16:21:48.092720240Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:48.093952 containerd[1598]: time="2025-11-03T16:21:48.093890047Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=73791811" Nov 3 16:21:48.095065 containerd[1598]: time="2025-11-03T16:21:48.095015606Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:48.097354 containerd[1598]: time="2025-11-03T16:21:48.097317626Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:48.098182 containerd[1598]: time="2025-11-03T16:21:48.098138341Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.897936405s" Nov 3 16:21:48.098182 containerd[1598]: time="2025-11-03T16:21:48.098169220Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Nov 3 16:21:48.101804 containerd[1598]: time="2025-11-03T16:21:48.101766835Z" level=info msg="CreateContainer within sandbox \"f15c3c8acb8e273183f1b5f3cfa0083f1fad54e005b8d1fa238bebe492647f12\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 3 16:21:48.108738 containerd[1598]: time="2025-11-03T16:21:48.108686877Z" level=info msg="Container 1129723a7daa2c1e7ca040be88aa5aa5e636d0fe22cf257e155583ff5807c115: CDI devices from CRI Config.CDIDevices: []" Nov 3 16:21:48.116207 containerd[1598]: time="2025-11-03T16:21:48.116164842Z" level=info msg="CreateContainer within sandbox \"f15c3c8acb8e273183f1b5f3cfa0083f1fad54e005b8d1fa238bebe492647f12\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"1129723a7daa2c1e7ca040be88aa5aa5e636d0fe22cf257e155583ff5807c115\"" Nov 3 16:21:48.116623 containerd[1598]: time="2025-11-03T16:21:48.116585673Z" level=info msg="StartContainer for \"1129723a7daa2c1e7ca040be88aa5aa5e636d0fe22cf257e155583ff5807c115\"" Nov 3 16:21:48.117598 containerd[1598]: time="2025-11-03T16:21:48.117571312Z" level=info msg="connecting to shim 1129723a7daa2c1e7ca040be88aa5aa5e636d0fe22cf257e155583ff5807c115" address="unix:///run/containerd/s/d8b8a17490754ba09723caf9e427bed6b3c41cb9bd7b9edb479f94afc68b472c" protocol=ttrpc version=3 Nov 3 16:21:48.141010 systemd[1]: Started cri-containerd-1129723a7daa2c1e7ca040be88aa5aa5e636d0fe22cf257e155583ff5807c115.scope - libcontainer container 1129723a7daa2c1e7ca040be88aa5aa5e636d0fe22cf257e155583ff5807c115. Nov 3 16:21:48.171392 kubelet[1897]: E1103 16:21:48.171346 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:48.183897 containerd[1598]: time="2025-11-03T16:21:48.183837744Z" level=info msg="StartContainer for \"1129723a7daa2c1e7ca040be88aa5aa5e636d0fe22cf257e155583ff5807c115\" returns successfully" Nov 3 16:21:48.386658 kubelet[1897]: I1103 16:21:48.386480 1897 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.487269997 podStartE2EDuration="6.386462188s" podCreationTimestamp="2025-11-03 16:21:42 +0000 UTC" firstStartedPulling="2025-11-03 16:21:43.199805551 +0000 UTC m=+36.155802856" lastFinishedPulling="2025-11-03 16:21:48.098997742 +0000 UTC m=+41.054995047" observedRunningTime="2025-11-03 16:21:48.385978839 +0000 UTC m=+41.341976144" watchObservedRunningTime="2025-11-03 16:21:48.386462188 +0000 UTC m=+41.342459493" Nov 3 16:21:49.142449 kubelet[1897]: E1103 16:21:49.142380 1897 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:49.171988 kubelet[1897]: E1103 16:21:49.171925 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:50.172582 kubelet[1897]: E1103 16:21:50.172512 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:51.173142 kubelet[1897]: E1103 16:21:51.173066 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:52.173858 kubelet[1897]: E1103 16:21:52.173794 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:53.174336 kubelet[1897]: E1103 16:21:53.174256 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:53.658404 systemd[1]: Created slice kubepods-besteffort-pod29dfde0c_18a0_49ba_904f_7b02ff0189bc.slice - libcontainer container kubepods-besteffort-pod29dfde0c_18a0_49ba_904f_7b02ff0189bc.slice. Nov 3 16:21:53.712219 kubelet[1897]: I1103 16:21:53.712163 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c179dc35-6d63-4900-8863-b713f5d3f6bc\" (UniqueName: \"kubernetes.io/nfs/29dfde0c-18a0-49ba-904f-7b02ff0189bc-pvc-c179dc35-6d63-4900-8863-b713f5d3f6bc\") pod \"test-pod-1\" (UID: \"29dfde0c-18a0-49ba-904f-7b02ff0189bc\") " pod="default/test-pod-1" Nov 3 16:21:53.712219 kubelet[1897]: I1103 16:21:53.712205 1897 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-btx2q\" (UniqueName: \"kubernetes.io/projected/29dfde0c-18a0-49ba-904f-7b02ff0189bc-kube-api-access-btx2q\") pod \"test-pod-1\" (UID: \"29dfde0c-18a0-49ba-904f-7b02ff0189bc\") " pod="default/test-pod-1" Nov 3 16:21:53.859998 kernel: netfs: FS-Cache loaded Nov 3 16:21:53.939702 kernel: RPC: Registered named UNIX socket transport module. Nov 3 16:21:53.939820 kernel: RPC: Registered udp transport module. Nov 3 16:21:53.939855 kernel: RPC: Registered tcp transport module. Nov 3 16:21:53.940692 kernel: RPC: Registered tcp-with-tls transport module. Nov 3 16:21:53.941828 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 3 16:21:54.174697 kubelet[1897]: E1103 16:21:54.174608 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:54.221207 kernel: NFS: Registering the id_resolver key type Nov 3 16:21:54.221288 kernel: Key type id_resolver registered Nov 3 16:21:54.221309 kernel: Key type id_legacy registered Nov 3 16:21:54.261499 nfsidmap[2988]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Nov 3 16:21:54.262370 nfsidmap[2988]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 3 16:21:54.267683 nfsidmap[2991]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Nov 3 16:21:54.267979 nfsidmap[2991]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 3 16:21:54.279139 nfsrahead[2995]: setting /var/lib/kubelet/pods/29dfde0c-18a0-49ba-904f-7b02ff0189bc/volumes/kubernetes.io~nfs/pvc-c179dc35-6d63-4900-8863-b713f5d3f6bc readahead to 128 Nov 3 16:21:54.562005 containerd[1598]: time="2025-11-03T16:21:54.561851284Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:29dfde0c-18a0-49ba-904f-7b02ff0189bc,Namespace:default,Attempt:0,}" Nov 3 16:21:54.574579 systemd-networkd[1506]: veth6900bad2: Link UP Nov 3 16:21:54.577310 kernel: cni0: port 3(veth6900bad2) entered blocking state Nov 3 16:21:54.577355 kernel: cni0: port 3(veth6900bad2) entered disabled state Nov 3 16:21:54.578440 kernel: veth6900bad2: entered allmulticast mode Nov 3 16:21:54.579525 kernel: veth6900bad2: entered promiscuous mode Nov 3 16:21:54.589347 kernel: cni0: port 3(veth6900bad2) entered blocking state Nov 3 16:21:54.589434 kernel: cni0: port 3(veth6900bad2) entered forwarding state Nov 3 16:21:54.589427 systemd-networkd[1506]: veth6900bad2: Gained carrier Nov 3 16:21:54.592318 containerd[1598]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Nov 3 16:21:54.592318 containerd[1598]: delegateAdd: netconf sent to delegate plugin: Nov 3 16:21:54.618229 containerd[1598]: {"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-11-03T16:21:54.618171413Z" level=info msg="connecting to shim 2c11b83848c1fa7715a150523cc2f9009ca2a1c6dde41ff5924a9cb346747b15" address="unix:///run/containerd/s/63f89bed182fe34f5728bd9354919e62b4013db681837c657aac9cf24ccad275" namespace=k8s.io protocol=ttrpc version=3 Nov 3 16:21:54.654043 systemd[1]: Started cri-containerd-2c11b83848c1fa7715a150523cc2f9009ca2a1c6dde41ff5924a9cb346747b15.scope - libcontainer container 2c11b83848c1fa7715a150523cc2f9009ca2a1c6dde41ff5924a9cb346747b15. Nov 3 16:21:54.671109 systemd-resolved[1300]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Nov 3 16:21:54.705165 containerd[1598]: time="2025-11-03T16:21:54.705110574Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:29dfde0c-18a0-49ba-904f-7b02ff0189bc,Namespace:default,Attempt:0,} returns sandbox id \"2c11b83848c1fa7715a150523cc2f9009ca2a1c6dde41ff5924a9cb346747b15\"" Nov 3 16:21:54.706283 containerd[1598]: time="2025-11-03T16:21:54.706220098Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 3 16:21:55.175601 kubelet[1897]: E1103 16:21:55.175508 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:55.435707 containerd[1598]: time="2025-11-03T16:21:55.435450095Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 3 16:21:55.436807 containerd[1598]: time="2025-11-03T16:21:55.436741512Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=0" Nov 3 16:21:55.439733 containerd[1598]: time="2025-11-03T16:21:55.439673758Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"73311824\" in 733.403364ms" Nov 3 16:21:55.439733 containerd[1598]: time="2025-11-03T16:21:55.439708050Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\"" Nov 3 16:21:55.443864 containerd[1598]: time="2025-11-03T16:21:55.443820982Z" level=info msg="CreateContainer within sandbox \"2c11b83848c1fa7715a150523cc2f9009ca2a1c6dde41ff5924a9cb346747b15\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 3 16:21:55.452533 containerd[1598]: time="2025-11-03T16:21:55.452467846Z" level=info msg="Container 05a511e58ecffc7515b057e349333e79fcbb1eb481c365346732279189a09644: CDI devices from CRI Config.CDIDevices: []" Nov 3 16:21:55.457558 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2154141169.mount: Deactivated successfully. Nov 3 16:21:55.459956 containerd[1598]: time="2025-11-03T16:21:55.459907998Z" level=info msg="CreateContainer within sandbox \"2c11b83848c1fa7715a150523cc2f9009ca2a1c6dde41ff5924a9cb346747b15\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"05a511e58ecffc7515b057e349333e79fcbb1eb481c365346732279189a09644\"" Nov 3 16:21:55.460519 containerd[1598]: time="2025-11-03T16:21:55.460482900Z" level=info msg="StartContainer for \"05a511e58ecffc7515b057e349333e79fcbb1eb481c365346732279189a09644\"" Nov 3 16:21:55.461771 containerd[1598]: time="2025-11-03T16:21:55.461731528Z" level=info msg="connecting to shim 05a511e58ecffc7515b057e349333e79fcbb1eb481c365346732279189a09644" address="unix:///run/containerd/s/63f89bed182fe34f5728bd9354919e62b4013db681837c657aac9cf24ccad275" protocol=ttrpc version=3 Nov 3 16:21:55.493080 systemd[1]: Started cri-containerd-05a511e58ecffc7515b057e349333e79fcbb1eb481c365346732279189a09644.scope - libcontainer container 05a511e58ecffc7515b057e349333e79fcbb1eb481c365346732279189a09644. Nov 3 16:21:55.549174 containerd[1598]: time="2025-11-03T16:21:55.549122452Z" level=info msg="StartContainer for \"05a511e58ecffc7515b057e349333e79fcbb1eb481c365346732279189a09644\" returns successfully" Nov 3 16:21:56.176686 kubelet[1897]: E1103 16:21:56.176588 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:56.262129 systemd-networkd[1506]: veth6900bad2: Gained IPv6LL Nov 3 16:21:56.403221 kubelet[1897]: I1103 16:21:56.403161 1897 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=12.668621578 podStartE2EDuration="13.403140599s" podCreationTimestamp="2025-11-03 16:21:43 +0000 UTC" firstStartedPulling="2025-11-03 16:21:54.705866985 +0000 UTC m=+47.661864280" lastFinishedPulling="2025-11-03 16:21:55.440385996 +0000 UTC m=+48.396383301" observedRunningTime="2025-11-03 16:21:56.402937096 +0000 UTC m=+49.358934411" watchObservedRunningTime="2025-11-03 16:21:56.403140599 +0000 UTC m=+49.359137904" Nov 3 16:21:57.177649 kubelet[1897]: E1103 16:21:57.177569 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:58.178588 kubelet[1897]: E1103 16:21:58.178523 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:21:59.179250 kubelet[1897]: E1103 16:21:59.179164 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 3 16:22:00.180185 kubelet[1897]: E1103 16:22:00.180105 1897 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"