Sep 5 00:38:46.902425 kernel: Linux version 6.12.44-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Thu Sep 4 22:12:48 -00 2025 Sep 5 00:38:46.902470 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=5ddbf8d117777441d6c5be3659126fb3de7a68afc9e620e02a4b6c5a60c1c503 Sep 5 00:38:46.902479 kernel: BIOS-provided physical RAM map: Sep 5 00:38:46.902486 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 5 00:38:46.902493 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 5 00:38:46.902500 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 5 00:38:46.902507 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Sep 5 00:38:46.902516 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Sep 5 00:38:46.902531 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 5 00:38:46.902540 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Sep 5 00:38:46.902549 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 5 00:38:46.902558 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 5 00:38:46.902567 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 5 00:38:46.902576 kernel: NX (Execute Disable) protection: active Sep 5 00:38:46.902591 kernel: APIC: Static calls initialized Sep 5 00:38:46.902601 kernel: SMBIOS 2.8 present. Sep 5 00:38:46.902615 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Sep 5 00:38:46.902624 kernel: DMI: Memory slots populated: 1/1 Sep 5 00:38:46.902633 kernel: Hypervisor detected: KVM Sep 5 00:38:46.902643 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 5 00:38:46.902652 kernel: kvm-clock: using sched offset of 4384077530 cycles Sep 5 00:38:46.902662 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 5 00:38:46.902672 kernel: tsc: Detected 2794.748 MHz processor Sep 5 00:38:46.902685 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 5 00:38:46.902694 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 5 00:38:46.902701 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Sep 5 00:38:46.902714 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 5 00:38:46.902724 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 5 00:38:46.902732 kernel: Using GB pages for direct mapping Sep 5 00:38:46.902739 kernel: ACPI: Early table checksum verification disabled Sep 5 00:38:46.902747 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Sep 5 00:38:46.902754 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:46.902766 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:46.902773 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:46.902781 kernel: ACPI: FACS 0x000000009CFE0000 000040 Sep 5 00:38:46.902788 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:46.902796 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:46.902804 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:46.902811 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:38:46.902819 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Sep 5 00:38:46.902832 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Sep 5 00:38:46.902840 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Sep 5 00:38:46.902848 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Sep 5 00:38:46.902856 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Sep 5 00:38:46.902863 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Sep 5 00:38:46.902871 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Sep 5 00:38:46.902881 kernel: No NUMA configuration found Sep 5 00:38:46.902889 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Sep 5 00:38:46.902897 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff] Sep 5 00:38:46.902904 kernel: Zone ranges: Sep 5 00:38:46.902918 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 5 00:38:46.902928 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Sep 5 00:38:46.902938 kernel: Normal empty Sep 5 00:38:46.902948 kernel: Device empty Sep 5 00:38:46.902958 kernel: Movable zone start for each node Sep 5 00:38:46.902968 kernel: Early memory node ranges Sep 5 00:38:46.902983 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 5 00:38:46.902993 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Sep 5 00:38:46.903003 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Sep 5 00:38:46.903013 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 5 00:38:46.903024 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 5 00:38:46.903045 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Sep 5 00:38:46.903053 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 5 00:38:46.903063 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 5 00:38:46.903070 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 5 00:38:46.903081 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 5 00:38:46.903089 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 5 00:38:46.903099 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 5 00:38:46.903106 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 5 00:38:46.903114 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 5 00:38:46.903121 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 5 00:38:46.903129 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 5 00:38:46.903137 kernel: TSC deadline timer available Sep 5 00:38:46.903144 kernel: CPU topo: Max. logical packages: 1 Sep 5 00:38:46.903154 kernel: CPU topo: Max. logical dies: 1 Sep 5 00:38:46.903161 kernel: CPU topo: Max. dies per package: 1 Sep 5 00:38:46.903169 kernel: CPU topo: Max. threads per core: 1 Sep 5 00:38:46.903176 kernel: CPU topo: Num. cores per package: 4 Sep 5 00:38:46.903184 kernel: CPU topo: Num. threads per package: 4 Sep 5 00:38:46.903191 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 5 00:38:46.903199 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 5 00:38:46.903207 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 5 00:38:46.903214 kernel: kvm-guest: setup PV sched yield Sep 5 00:38:46.903224 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Sep 5 00:38:46.903231 kernel: Booting paravirtualized kernel on KVM Sep 5 00:38:46.903248 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 5 00:38:46.903255 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 5 00:38:46.903266 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 5 00:38:46.903274 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 5 00:38:46.903281 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 5 00:38:46.903289 kernel: kvm-guest: PV spinlocks enabled Sep 5 00:38:46.903297 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 5 00:38:46.903308 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=5ddbf8d117777441d6c5be3659126fb3de7a68afc9e620e02a4b6c5a60c1c503 Sep 5 00:38:46.903316 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 5 00:38:46.903323 kernel: random: crng init done Sep 5 00:38:46.903331 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 5 00:38:46.903341 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 5 00:38:46.903351 kernel: Fallback order for Node 0: 0 Sep 5 00:38:46.903361 kernel: Built 1 zonelists, mobility grouping on. Total pages: 642938 Sep 5 00:38:46.903371 kernel: Policy zone: DMA32 Sep 5 00:38:46.903381 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 5 00:38:46.903395 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 5 00:38:46.903405 kernel: ftrace: allocating 40102 entries in 157 pages Sep 5 00:38:46.903414 kernel: ftrace: allocated 157 pages with 5 groups Sep 5 00:38:46.903422 kernel: Dynamic Preempt: voluntary Sep 5 00:38:46.903431 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 5 00:38:46.903442 kernel: rcu: RCU event tracing is enabled. Sep 5 00:38:46.903473 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 5 00:38:46.903481 kernel: Trampoline variant of Tasks RCU enabled. Sep 5 00:38:46.903493 kernel: Rude variant of Tasks RCU enabled. Sep 5 00:38:46.903507 kernel: Tracing variant of Tasks RCU enabled. Sep 5 00:38:46.903515 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 5 00:38:46.903524 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 5 00:38:46.903534 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 00:38:46.903545 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 00:38:46.903555 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 00:38:46.903565 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 5 00:38:46.903576 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 5 00:38:46.903604 kernel: Console: colour VGA+ 80x25 Sep 5 00:38:46.903615 kernel: printk: legacy console [ttyS0] enabled Sep 5 00:38:46.903625 kernel: ACPI: Core revision 20240827 Sep 5 00:38:46.903635 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 5 00:38:46.903648 kernel: APIC: Switch to symmetric I/O mode setup Sep 5 00:38:46.903657 kernel: x2apic enabled Sep 5 00:38:46.903667 kernel: APIC: Switched APIC routing to: physical x2apic Sep 5 00:38:46.903681 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 5 00:38:46.903691 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 5 00:38:46.903704 kernel: kvm-guest: setup PV IPIs Sep 5 00:38:46.903714 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 5 00:38:46.903725 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Sep 5 00:38:46.903739 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Sep 5 00:38:46.903747 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 5 00:38:46.903755 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 5 00:38:46.903763 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 5 00:38:46.903771 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 5 00:38:46.903780 kernel: Spectre V2 : Mitigation: Retpolines Sep 5 00:38:46.903788 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 5 00:38:46.903797 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 5 00:38:46.903807 kernel: active return thunk: retbleed_return_thunk Sep 5 00:38:46.903818 kernel: RETBleed: Mitigation: untrained return thunk Sep 5 00:38:46.903829 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 5 00:38:46.903840 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 5 00:38:46.903851 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 5 00:38:46.903862 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Sep 5 00:38:46.903876 kernel: active return thunk: srso_return_thunk Sep 5 00:38:46.903886 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 5 00:38:46.903897 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 5 00:38:46.903907 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 5 00:38:46.903918 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 5 00:38:46.903928 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 5 00:38:46.903939 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 5 00:38:46.903949 kernel: Freeing SMP alternatives memory: 32K Sep 5 00:38:46.903962 kernel: pid_max: default: 32768 minimum: 301 Sep 5 00:38:46.903972 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 5 00:38:46.903993 kernel: landlock: Up and running. Sep 5 00:38:46.904009 kernel: SELinux: Initializing. Sep 5 00:38:46.904021 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 00:38:46.904033 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 00:38:46.904052 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 5 00:38:46.904060 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 5 00:38:46.904068 kernel: ... version: 0 Sep 5 00:38:46.904080 kernel: ... bit width: 48 Sep 5 00:38:46.904087 kernel: ... generic registers: 6 Sep 5 00:38:46.904096 kernel: ... value mask: 0000ffffffffffff Sep 5 00:38:46.904103 kernel: ... max period: 00007fffffffffff Sep 5 00:38:46.904111 kernel: ... fixed-purpose events: 0 Sep 5 00:38:46.904119 kernel: ... event mask: 000000000000003f Sep 5 00:38:46.904127 kernel: signal: max sigframe size: 1776 Sep 5 00:38:46.904135 kernel: rcu: Hierarchical SRCU implementation. Sep 5 00:38:46.904143 kernel: rcu: Max phase no-delay instances is 400. Sep 5 00:38:46.904151 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 5 00:38:46.904161 kernel: smp: Bringing up secondary CPUs ... Sep 5 00:38:46.904169 kernel: smpboot: x86: Booting SMP configuration: Sep 5 00:38:46.904177 kernel: .... node #0, CPUs: #1 #2 #3 Sep 5 00:38:46.904184 kernel: smp: Brought up 1 node, 4 CPUs Sep 5 00:38:46.904192 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Sep 5 00:38:46.904201 kernel: Memory: 2428920K/2571752K available (14336K kernel code, 2428K rwdata, 9956K rodata, 54044K init, 2924K bss, 136904K reserved, 0K cma-reserved) Sep 5 00:38:46.904214 kernel: devtmpfs: initialized Sep 5 00:38:46.904222 kernel: x86/mm: Memory block size: 128MB Sep 5 00:38:46.904230 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 5 00:38:46.904252 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 5 00:38:46.904259 kernel: pinctrl core: initialized pinctrl subsystem Sep 5 00:38:46.904268 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 5 00:38:46.904276 kernel: audit: initializing netlink subsys (disabled) Sep 5 00:38:46.904295 kernel: audit: type=2000 audit(1757032724.036:1): state=initialized audit_enabled=0 res=1 Sep 5 00:38:46.904311 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 5 00:38:46.904334 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 5 00:38:46.904347 kernel: cpuidle: using governor menu Sep 5 00:38:46.904358 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 5 00:38:46.904384 kernel: dca service started, version 1.12.1 Sep 5 00:38:46.904407 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] Sep 5 00:38:46.904429 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 5 00:38:46.904463 kernel: PCI: Using configuration type 1 for base access Sep 5 00:38:46.904476 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 5 00:38:46.904486 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 5 00:38:46.904507 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 5 00:38:46.904527 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 5 00:38:46.904551 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 5 00:38:46.904561 kernel: ACPI: Added _OSI(Module Device) Sep 5 00:38:46.904569 kernel: ACPI: Added _OSI(Processor Device) Sep 5 00:38:46.904576 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 5 00:38:46.904592 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 5 00:38:46.904602 kernel: ACPI: Interpreter enabled Sep 5 00:38:46.904618 kernel: ACPI: PM: (supports S0 S3 S5) Sep 5 00:38:46.904641 kernel: ACPI: Using IOAPIC for interrupt routing Sep 5 00:38:46.904661 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 5 00:38:46.904678 kernel: PCI: Using E820 reservations for host bridge windows Sep 5 00:38:46.904698 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 5 00:38:46.904716 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 5 00:38:46.905095 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 5 00:38:46.905493 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 5 00:38:46.905639 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 5 00:38:46.905654 kernel: PCI host bridge to bus 0000:00 Sep 5 00:38:46.905839 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 5 00:38:46.905964 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 5 00:38:46.906078 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 5 00:38:46.906219 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 5 00:38:46.906364 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 5 00:38:46.906510 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Sep 5 00:38:46.906660 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 5 00:38:46.906874 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 5 00:38:46.907026 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 5 00:38:46.907165 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Sep 5 00:38:46.907334 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Sep 5 00:38:46.907507 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Sep 5 00:38:46.907699 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 5 00:38:46.907895 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 5 00:38:46.908052 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Sep 5 00:38:46.908205 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Sep 5 00:38:46.908350 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Sep 5 00:38:46.908608 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 5 00:38:46.908735 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Sep 5 00:38:46.908857 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Sep 5 00:38:46.908977 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Sep 5 00:38:46.909144 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 5 00:38:46.909306 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Sep 5 00:38:46.909430 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Sep 5 00:38:46.909580 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Sep 5 00:38:46.909737 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Sep 5 00:38:46.909934 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 5 00:38:46.910173 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 5 00:38:46.910482 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 5 00:38:46.910742 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Sep 5 00:38:46.910965 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Sep 5 00:38:46.911137 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 5 00:38:46.911276 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Sep 5 00:38:46.911287 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 5 00:38:46.911301 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 5 00:38:46.911309 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 5 00:38:46.911317 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 5 00:38:46.911325 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 5 00:38:46.911335 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 5 00:38:46.911346 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 5 00:38:46.911356 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 5 00:38:46.911367 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 5 00:38:46.911379 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 5 00:38:46.911393 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 5 00:38:46.911404 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 5 00:38:46.911414 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 5 00:38:46.911425 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 5 00:38:46.911435 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 5 00:38:46.911446 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 5 00:38:46.911477 kernel: iommu: Default domain type: Translated Sep 5 00:38:46.911488 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 5 00:38:46.911499 kernel: PCI: Using ACPI for IRQ routing Sep 5 00:38:46.911510 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 5 00:38:46.911518 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 5 00:38:46.911526 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Sep 5 00:38:46.911691 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 5 00:38:46.911835 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 5 00:38:46.912003 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 5 00:38:46.912016 kernel: vgaarb: loaded Sep 5 00:38:46.912025 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 5 00:38:46.912038 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 5 00:38:46.912046 kernel: clocksource: Switched to clocksource kvm-clock Sep 5 00:38:46.912054 kernel: VFS: Disk quotas dquot_6.6.0 Sep 5 00:38:46.912062 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 5 00:38:46.912072 kernel: pnp: PnP ACPI init Sep 5 00:38:46.912272 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 5 00:38:46.912301 kernel: pnp: PnP ACPI: found 6 devices Sep 5 00:38:46.912312 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 5 00:38:46.912329 kernel: NET: Registered PF_INET protocol family Sep 5 00:38:46.912339 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 5 00:38:46.912349 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 5 00:38:46.912360 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 5 00:38:46.912372 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 5 00:38:46.912382 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 5 00:38:46.912393 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 5 00:38:46.912404 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 00:38:46.912414 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 00:38:46.912426 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 5 00:38:46.912434 kernel: NET: Registered PF_XDP protocol family Sep 5 00:38:46.914215 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 5 00:38:46.914365 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 5 00:38:46.914530 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 5 00:38:46.914687 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 5 00:38:46.914812 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 5 00:38:46.914931 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Sep 5 00:38:46.914943 kernel: PCI: CLS 0 bytes, default 64 Sep 5 00:38:46.914956 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Sep 5 00:38:46.914964 kernel: Initialise system trusted keyrings Sep 5 00:38:46.914972 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 5 00:38:46.914980 kernel: Key type asymmetric registered Sep 5 00:38:46.914988 kernel: Asymmetric key parser 'x509' registered Sep 5 00:38:46.914996 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 5 00:38:46.915005 kernel: io scheduler mq-deadline registered Sep 5 00:38:46.915013 kernel: io scheduler kyber registered Sep 5 00:38:46.915022 kernel: io scheduler bfq registered Sep 5 00:38:46.915035 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 5 00:38:46.915047 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 5 00:38:46.915058 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 5 00:38:46.915069 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 5 00:38:46.915080 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 5 00:38:46.915091 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 5 00:38:46.915102 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 5 00:38:46.915112 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 5 00:38:46.915121 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 5 00:38:46.915291 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 5 00:38:46.915305 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 5 00:38:46.915426 kernel: rtc_cmos 00:04: registered as rtc0 Sep 5 00:38:46.915591 kernel: rtc_cmos 00:04: setting system clock to 2025-09-05T00:38:46 UTC (1757032726) Sep 5 00:38:46.915762 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 5 00:38:46.915786 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 5 00:38:46.915800 kernel: NET: Registered PF_INET6 protocol family Sep 5 00:38:46.915818 kernel: Segment Routing with IPv6 Sep 5 00:38:46.915828 kernel: In-situ OAM (IOAM) with IPv6 Sep 5 00:38:46.915839 kernel: NET: Registered PF_PACKET protocol family Sep 5 00:38:46.915849 kernel: Key type dns_resolver registered Sep 5 00:38:46.915859 kernel: IPI shorthand broadcast: enabled Sep 5 00:38:46.915869 kernel: sched_clock: Marking stable (3168002484, 117704661)->(3308589637, -22882492) Sep 5 00:38:46.915879 kernel: registered taskstats version 1 Sep 5 00:38:46.915888 kernel: Loading compiled-in X.509 certificates Sep 5 00:38:46.915899 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.44-flatcar: 55c9ce6358d6eed45ca94030a2308729ee6a249f' Sep 5 00:38:46.915908 kernel: Demotion targets for Node 0: null Sep 5 00:38:46.915918 kernel: Key type .fscrypt registered Sep 5 00:38:46.915926 kernel: Key type fscrypt-provisioning registered Sep 5 00:38:46.915934 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 5 00:38:46.915942 kernel: ima: Allocated hash algorithm: sha1 Sep 5 00:38:46.915950 kernel: ima: No architecture policies found Sep 5 00:38:46.915958 kernel: clk: Disabling unused clocks Sep 5 00:38:46.915966 kernel: Warning: unable to open an initial console. Sep 5 00:38:46.915974 kernel: Freeing unused kernel image (initmem) memory: 54044K Sep 5 00:38:46.915985 kernel: Write protecting the kernel read-only data: 24576k Sep 5 00:38:46.915996 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Sep 5 00:38:46.916007 kernel: Run /init as init process Sep 5 00:38:46.916018 kernel: with arguments: Sep 5 00:38:46.916028 kernel: /init Sep 5 00:38:46.916035 kernel: with environment: Sep 5 00:38:46.916043 kernel: HOME=/ Sep 5 00:38:46.916051 kernel: TERM=linux Sep 5 00:38:46.916059 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 5 00:38:46.916071 systemd[1]: Successfully made /usr/ read-only. Sep 5 00:38:46.916093 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 5 00:38:46.916105 systemd[1]: Detected virtualization kvm. Sep 5 00:38:46.916113 systemd[1]: Detected architecture x86-64. Sep 5 00:38:46.916122 systemd[1]: Running in initrd. Sep 5 00:38:46.916130 systemd[1]: No hostname configured, using default hostname. Sep 5 00:38:46.916141 systemd[1]: Hostname set to . Sep 5 00:38:46.916150 systemd[1]: Initializing machine ID from VM UUID. Sep 5 00:38:46.916158 systemd[1]: Queued start job for default target initrd.target. Sep 5 00:38:46.916168 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:38:46.916179 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:38:46.916191 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 5 00:38:46.916203 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 00:38:46.916217 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 5 00:38:46.916230 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 5 00:38:46.916252 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 5 00:38:46.916261 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 5 00:38:46.916270 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:38:46.916280 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:38:46.916289 systemd[1]: Reached target paths.target - Path Units. Sep 5 00:38:46.916300 systemd[1]: Reached target slices.target - Slice Units. Sep 5 00:38:46.916309 systemd[1]: Reached target swap.target - Swaps. Sep 5 00:38:46.916318 systemd[1]: Reached target timers.target - Timer Units. Sep 5 00:38:46.916327 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 00:38:46.916335 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 00:38:46.916344 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 5 00:38:46.916353 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 5 00:38:46.916362 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:38:46.916370 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 00:38:46.916381 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:38:46.916392 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 00:38:46.916401 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 5 00:38:46.916410 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 00:38:46.916421 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 5 00:38:46.916432 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 5 00:38:46.916441 systemd[1]: Starting systemd-fsck-usr.service... Sep 5 00:38:46.916464 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 00:38:46.916474 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 00:38:46.916482 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:38:46.916491 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 5 00:38:46.916543 systemd-journald[219]: Collecting audit messages is disabled. Sep 5 00:38:46.916565 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:38:46.916577 systemd[1]: Finished systemd-fsck-usr.service. Sep 5 00:38:46.916586 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 5 00:38:46.916596 systemd-journald[219]: Journal started Sep 5 00:38:46.916619 systemd-journald[219]: Runtime Journal (/run/log/journal/972009bddbbe4a9b9ede0a6eebf3bd5d) is 6M, max 48.6M, 42.5M free. Sep 5 00:38:46.924490 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 00:38:46.930585 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 00:38:46.953745 systemd-modules-load[221]: Inserted module 'overlay' Sep 5 00:38:46.998773 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 5 00:38:46.998824 kernel: Bridge firewalling registered Sep 5 00:38:46.958426 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:38:46.984626 systemd-modules-load[221]: Inserted module 'br_netfilter' Sep 5 00:38:47.000679 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 00:38:47.001379 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:47.004167 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 00:38:47.005579 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 00:38:47.008563 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 00:38:47.040054 systemd-tmpfiles[236]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 5 00:38:47.040116 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:38:47.041817 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:38:47.046262 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:38:47.048733 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 00:38:47.068836 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:38:47.070849 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 5 00:38:47.113044 dracut-cmdline[262]: 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=5ddbf8d117777441d6c5be3659126fb3de7a68afc9e620e02a4b6c5a60c1c503 Sep 5 00:38:47.119307 systemd-resolved[257]: Positive Trust Anchors: Sep 5 00:38:47.119327 systemd-resolved[257]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 00:38:47.119373 systemd-resolved[257]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 5 00:38:47.122575 systemd-resolved[257]: Defaulting to hostname 'linux'. Sep 5 00:38:47.124254 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 00:38:47.130184 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:38:47.273585 kernel: SCSI subsystem initialized Sep 5 00:38:47.284491 kernel: Loading iSCSI transport class v2.0-870. Sep 5 00:38:47.298511 kernel: iscsi: registered transport (tcp) Sep 5 00:38:47.323684 kernel: iscsi: registered transport (qla4xxx) Sep 5 00:38:47.323788 kernel: QLogic iSCSI HBA Driver Sep 5 00:38:47.349094 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 5 00:38:47.387616 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 00:38:47.398443 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 5 00:38:47.473124 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 5 00:38:47.474819 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 5 00:38:47.545579 kernel: raid6: avx2x4 gen() 24405 MB/s Sep 5 00:38:47.562514 kernel: raid6: avx2x2 gen() 23440 MB/s Sep 5 00:38:47.579859 kernel: raid6: avx2x1 gen() 20157 MB/s Sep 5 00:38:47.579929 kernel: raid6: using algorithm avx2x4 gen() 24405 MB/s Sep 5 00:38:47.597931 kernel: raid6: .... xor() 5592 MB/s, rmw enabled Sep 5 00:38:47.598042 kernel: raid6: using avx2x2 recovery algorithm Sep 5 00:38:47.628527 kernel: xor: automatically using best checksumming function avx Sep 5 00:38:47.836510 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 5 00:38:47.848121 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 5 00:38:47.850746 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:38:47.886716 systemd-udevd[472]: Using default interface naming scheme 'v255'. Sep 5 00:38:47.892989 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:38:47.897859 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 5 00:38:47.925364 dracut-pre-trigger[475]: rd.md=0: removing MD RAID activation Sep 5 00:38:47.987823 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 00:38:48.005843 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 00:38:48.099607 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:38:48.105512 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 5 00:38:48.170516 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 5 00:38:48.177388 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 5 00:38:48.181133 kernel: cryptd: max_cpu_qlen set to 1000 Sep 5 00:38:48.185420 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 5 00:38:48.185464 kernel: GPT:9289727 != 19775487 Sep 5 00:38:48.185476 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 5 00:38:48.185487 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 5 00:38:48.186653 kernel: GPT:9289727 != 19775487 Sep 5 00:38:48.186676 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 5 00:38:48.188215 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 00:38:48.200312 kernel: libata version 3.00 loaded. Sep 5 00:38:48.207532 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:38:48.233598 kernel: AES CTR mode by8 optimization enabled Sep 5 00:38:48.207704 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:48.233614 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:38:48.234732 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:38:48.236900 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 5 00:38:48.257476 kernel: ahci 0000:00:1f.2: version 3.0 Sep 5 00:38:48.273596 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 5 00:38:48.277895 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 5 00:38:48.278214 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 5 00:38:48.278383 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 5 00:38:48.281754 kernel: scsi host0: ahci Sep 5 00:38:48.281989 kernel: scsi host1: ahci Sep 5 00:38:48.282688 kernel: scsi host2: ahci Sep 5 00:38:48.283491 kernel: scsi host3: ahci Sep 5 00:38:48.285893 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 5 00:38:48.288218 kernel: scsi host4: ahci Sep 5 00:38:48.290475 kernel: scsi host5: ahci Sep 5 00:38:48.290716 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 lpm-pol 1 Sep 5 00:38:48.290744 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 lpm-pol 1 Sep 5 00:38:48.291841 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 lpm-pol 1 Sep 5 00:38:48.291868 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 lpm-pol 1 Sep 5 00:38:48.293621 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 lpm-pol 1 Sep 5 00:38:48.293645 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 lpm-pol 1 Sep 5 00:38:48.305717 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 5 00:38:48.329850 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 5 00:38:48.339877 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 5 00:38:48.341637 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:48.353672 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 5 00:38:48.369024 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 5 00:38:48.404050 disk-uuid[631]: Primary Header is updated. Sep 5 00:38:48.404050 disk-uuid[631]: Secondary Entries is updated. Sep 5 00:38:48.404050 disk-uuid[631]: Secondary Header is updated. Sep 5 00:38:48.409490 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 00:38:48.415482 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 00:38:48.607636 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:48.607736 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 5 00:38:48.607754 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:48.607766 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:48.608468 kernel: ata3.00: LPM support broken, forcing max_power Sep 5 00:38:48.609972 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 5 00:38:48.610009 kernel: ata3.00: applying bridge limits Sep 5 00:38:48.611519 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:48.612501 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 5 00:38:48.612525 kernel: ata3.00: LPM support broken, forcing max_power Sep 5 00:38:48.613546 kernel: ata3.00: configured for UDMA/100 Sep 5 00:38:48.614501 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 5 00:38:48.678494 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 5 00:38:48.678888 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 5 00:38:48.705500 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 5 00:38:49.146775 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 5 00:38:49.148774 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 00:38:49.150878 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:38:49.154626 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 00:38:49.157170 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 5 00:38:49.204788 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 5 00:38:49.431481 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 00:38:49.432576 disk-uuid[632]: The operation has completed successfully. Sep 5 00:38:49.468051 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 5 00:38:49.469122 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 5 00:38:49.553583 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 5 00:38:49.580100 sh[662]: Success Sep 5 00:38:49.606336 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 5 00:38:49.606411 kernel: device-mapper: uevent: version 1.0.3 Sep 5 00:38:49.606430 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 5 00:38:49.616472 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 5 00:38:49.649355 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 5 00:38:49.666128 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 5 00:38:49.681476 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 5 00:38:49.696628 kernel: BTRFS: device fsid bbfaff22-5589-4cab-94aa-ce3e6be0b7e8 devid 1 transid 36 /dev/mapper/usr (253:0) scanned by mount (674) Sep 5 00:38:49.696688 kernel: BTRFS info (device dm-0): first mount of filesystem bbfaff22-5589-4cab-94aa-ce3e6be0b7e8 Sep 5 00:38:49.696703 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:49.705713 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 5 00:38:49.705804 kernel: BTRFS info (device dm-0): enabling free space tree Sep 5 00:38:49.707526 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 5 00:38:49.709238 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 5 00:38:49.711143 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 5 00:38:49.714846 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 5 00:38:49.717290 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 5 00:38:49.750504 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (707) Sep 5 00:38:49.752971 kernel: BTRFS info (device vda6): first mount of filesystem f4b20ae7-6320-4f9d-b17c-1a32a98200fb Sep 5 00:38:49.753031 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:49.757069 kernel: BTRFS info (device vda6): turning on async discard Sep 5 00:38:49.757151 kernel: BTRFS info (device vda6): enabling free space tree Sep 5 00:38:49.762501 kernel: BTRFS info (device vda6): last unmount of filesystem f4b20ae7-6320-4f9d-b17c-1a32a98200fb Sep 5 00:38:49.763684 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 5 00:38:49.768122 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 5 00:38:49.934690 ignition[750]: Ignition 2.21.0 Sep 5 00:38:49.934709 ignition[750]: Stage: fetch-offline Sep 5 00:38:49.935759 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 00:38:49.934751 ignition[750]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:49.940051 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 00:38:49.934763 ignition[750]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:49.934876 ignition[750]: parsed url from cmdline: "" Sep 5 00:38:49.934881 ignition[750]: no config URL provided Sep 5 00:38:49.934887 ignition[750]: reading system config file "/usr/lib/ignition/user.ign" Sep 5 00:38:49.934899 ignition[750]: no config at "/usr/lib/ignition/user.ign" Sep 5 00:38:49.934932 ignition[750]: op(1): [started] loading QEMU firmware config module Sep 5 00:38:49.934939 ignition[750]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 5 00:38:49.948608 ignition[750]: op(1): [finished] loading QEMU firmware config module Sep 5 00:38:49.951091 ignition[750]: parsing config with SHA512: b3bf26c57cf78b73e150b4169009750ed0b33a419f096693db4f3794fe324bf1023fdead6a4e39347059728637305cd04ef5ad1e589edea3ef263fc2e6a6598e Sep 5 00:38:49.954022 unknown[750]: fetched base config from "system" Sep 5 00:38:49.954041 unknown[750]: fetched user config from "qemu" Sep 5 00:38:49.954404 ignition[750]: fetch-offline: fetch-offline passed Sep 5 00:38:49.954504 ignition[750]: Ignition finished successfully Sep 5 00:38:49.959020 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 00:38:49.992836 systemd-networkd[851]: lo: Link UP Sep 5 00:38:49.992852 systemd-networkd[851]: lo: Gained carrier Sep 5 00:38:49.994752 systemd-networkd[851]: Enumeration completed Sep 5 00:38:49.995027 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 00:38:49.995228 systemd-networkd[851]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:38:49.995234 systemd-networkd[851]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:38:49.997897 systemd-networkd[851]: eth0: Link UP Sep 5 00:38:50.000955 systemd-networkd[851]: eth0: Gained carrier Sep 5 00:38:50.000984 systemd-networkd[851]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:38:50.001940 systemd[1]: Reached target network.target - Network. Sep 5 00:38:50.004062 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 5 00:38:50.007024 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 5 00:38:50.023598 systemd-networkd[851]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 5 00:38:50.062270 ignition[855]: Ignition 2.21.0 Sep 5 00:38:50.062294 ignition[855]: Stage: kargs Sep 5 00:38:50.062545 ignition[855]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:50.062564 ignition[855]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:50.063397 ignition[855]: kargs: kargs passed Sep 5 00:38:50.063490 ignition[855]: Ignition finished successfully Sep 5 00:38:50.070650 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 5 00:38:50.075058 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 5 00:38:50.084483 systemd-resolved[257]: Detected conflict on linux IN A 10.0.0.129 Sep 5 00:38:50.084596 systemd-resolved[257]: Hostname conflict, changing published hostname from 'linux' to 'linux3'. Sep 5 00:38:50.117647 ignition[864]: Ignition 2.21.0 Sep 5 00:38:50.117662 ignition[864]: Stage: disks Sep 5 00:38:50.118039 ignition[864]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:50.118051 ignition[864]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:50.121696 ignition[864]: disks: disks passed Sep 5 00:38:50.121794 ignition[864]: Ignition finished successfully Sep 5 00:38:50.155586 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 5 00:38:50.156903 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 5 00:38:50.158955 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 5 00:38:50.160198 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 00:38:50.161258 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 00:38:50.163261 systemd[1]: Reached target basic.target - Basic System. Sep 5 00:38:50.166543 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 5 00:38:50.207548 systemd-fsck[874]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 5 00:38:50.217025 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 5 00:38:50.220267 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 5 00:38:50.441483 kernel: EXT4-fs (vda9): mounted filesystem a99dab41-6cdd-4037-a941-eeee48403b9e r/w with ordered data mode. Quota mode: none. Sep 5 00:38:50.442090 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 5 00:38:50.443955 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 5 00:38:50.445677 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 00:38:50.448568 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 5 00:38:50.450781 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 5 00:38:50.450856 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 5 00:38:50.450897 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 00:38:50.467641 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 5 00:38:50.471617 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (882) Sep 5 00:38:50.471647 kernel: BTRFS info (device vda6): first mount of filesystem f4b20ae7-6320-4f9d-b17c-1a32a98200fb Sep 5 00:38:50.469761 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 5 00:38:50.472817 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:50.478662 kernel: BTRFS info (device vda6): turning on async discard Sep 5 00:38:50.478719 kernel: BTRFS info (device vda6): enabling free space tree Sep 5 00:38:50.481866 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 00:38:50.514016 initrd-setup-root[906]: cut: /sysroot/etc/passwd: No such file or directory Sep 5 00:38:50.520050 initrd-setup-root[913]: cut: /sysroot/etc/group: No such file or directory Sep 5 00:38:50.526160 initrd-setup-root[920]: cut: /sysroot/etc/shadow: No such file or directory Sep 5 00:38:50.532029 initrd-setup-root[927]: cut: /sysroot/etc/gshadow: No such file or directory Sep 5 00:38:50.649968 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 5 00:38:50.651262 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 5 00:38:50.653900 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 5 00:38:50.685509 kernel: BTRFS info (device vda6): last unmount of filesystem f4b20ae7-6320-4f9d-b17c-1a32a98200fb Sep 5 00:38:50.694519 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 5 00:38:50.703700 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 5 00:38:50.740733 ignition[996]: INFO : Ignition 2.21.0 Sep 5 00:38:50.740733 ignition[996]: INFO : Stage: mount Sep 5 00:38:50.742933 ignition[996]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:50.742933 ignition[996]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:50.747693 ignition[996]: INFO : mount: mount passed Sep 5 00:38:50.748770 ignition[996]: INFO : Ignition finished successfully Sep 5 00:38:50.753060 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 5 00:38:50.756864 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 5 00:38:50.788527 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 00:38:50.818044 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1008) Sep 5 00:38:50.818107 kernel: BTRFS info (device vda6): first mount of filesystem f4b20ae7-6320-4f9d-b17c-1a32a98200fb Sep 5 00:38:50.818119 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:38:50.822481 kernel: BTRFS info (device vda6): turning on async discard Sep 5 00:38:50.822506 kernel: BTRFS info (device vda6): enabling free space tree Sep 5 00:38:50.824384 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 00:38:50.868234 ignition[1025]: INFO : Ignition 2.21.0 Sep 5 00:38:50.868234 ignition[1025]: INFO : Stage: files Sep 5 00:38:50.870160 ignition[1025]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:50.870160 ignition[1025]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:50.870160 ignition[1025]: DEBUG : files: compiled without relabeling support, skipping Sep 5 00:38:50.874025 ignition[1025]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 5 00:38:50.874025 ignition[1025]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 5 00:38:50.877107 ignition[1025]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 5 00:38:50.877107 ignition[1025]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 5 00:38:50.877107 ignition[1025]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 5 00:38:50.875105 unknown[1025]: wrote ssh authorized keys file for user: core Sep 5 00:38:50.882809 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 5 00:38:50.882809 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 5 00:38:50.882809 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 00:38:50.882809 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 00:38:50.882809 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Sep 5 00:38:50.893439 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Sep 5 00:38:50.893439 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Sep 5 00:38:50.893439 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-x86-64.raw: attempt #1 Sep 5 00:38:51.273656 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 5 00:38:51.414774 systemd-networkd[851]: eth0: Gained IPv6LL Sep 5 00:38:52.111270 ignition[1025]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Sep 5 00:38:52.111270 ignition[1025]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 5 00:38:52.115299 ignition[1025]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 5 00:38:52.328036 ignition[1025]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 5 00:38:52.328036 ignition[1025]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 5 00:38:52.328036 ignition[1025]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 5 00:38:52.355741 ignition[1025]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 5 00:38:52.363546 ignition[1025]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 5 00:38:52.365327 ignition[1025]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 5 00:38:52.366843 ignition[1025]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 5 00:38:52.368737 ignition[1025]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 5 00:38:52.370416 ignition[1025]: INFO : files: files passed Sep 5 00:38:52.371211 ignition[1025]: INFO : Ignition finished successfully Sep 5 00:38:52.375382 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 5 00:38:52.378001 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 5 00:38:52.380919 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 5 00:38:52.401008 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 5 00:38:52.401194 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 5 00:38:52.405189 initrd-setup-root-after-ignition[1053]: grep: /sysroot/oem/oem-release: No such file or directory Sep 5 00:38:52.409904 initrd-setup-root-after-ignition[1056]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:38:52.409904 initrd-setup-root-after-ignition[1056]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:38:52.413352 initrd-setup-root-after-ignition[1060]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:38:52.416602 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 00:38:52.416966 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 5 00:38:52.421213 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 5 00:38:52.491975 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 5 00:38:52.492135 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 5 00:38:52.494601 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 5 00:38:52.495676 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 5 00:38:52.497606 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 5 00:38:52.501326 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 5 00:38:52.540543 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 00:38:52.543800 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 5 00:38:52.599347 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:38:52.648002 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:38:52.650194 systemd[1]: Stopped target timers.target - Timer Units. Sep 5 00:38:52.651354 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 5 00:38:52.651505 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 00:38:52.655360 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 5 00:38:52.657708 systemd[1]: Stopped target basic.target - Basic System. Sep 5 00:38:52.659593 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 5 00:38:52.660496 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 00:38:52.660955 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 5 00:38:52.661293 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 5 00:38:52.661776 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 5 00:38:52.662092 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 00:38:52.662425 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 5 00:38:52.662896 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 5 00:38:52.663219 systemd[1]: Stopped target swap.target - Swaps. Sep 5 00:38:52.663688 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 5 00:38:52.663809 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 5 00:38:52.691307 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:38:52.693286 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:38:52.694490 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 5 00:38:52.694785 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:38:52.695119 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 5 00:38:52.695226 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 5 00:38:52.700507 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 5 00:38:52.700626 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 00:38:52.701760 systemd[1]: Stopped target paths.target - Path Units. Sep 5 00:38:52.701986 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 5 00:38:52.705617 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:38:52.708240 systemd[1]: Stopped target slices.target - Slice Units. Sep 5 00:38:52.709250 systemd[1]: Stopped target sockets.target - Socket Units. Sep 5 00:38:52.711529 systemd[1]: iscsid.socket: Deactivated successfully. Sep 5 00:38:52.711654 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 00:38:52.711997 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 5 00:38:52.712107 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 00:38:52.714837 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 5 00:38:52.714996 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 00:38:52.716516 systemd[1]: ignition-files.service: Deactivated successfully. Sep 5 00:38:52.716651 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 5 00:38:52.721117 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 5 00:38:52.724123 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 5 00:38:52.724954 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 5 00:38:52.725091 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:38:52.726734 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 5 00:38:52.726882 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 00:38:52.737930 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 5 00:38:52.738064 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 5 00:38:52.754691 ignition[1080]: INFO : Ignition 2.21.0 Sep 5 00:38:52.754691 ignition[1080]: INFO : Stage: umount Sep 5 00:38:52.757328 ignition[1080]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:38:52.757328 ignition[1080]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 00:38:52.757328 ignition[1080]: INFO : umount: umount passed Sep 5 00:38:52.757328 ignition[1080]: INFO : Ignition finished successfully Sep 5 00:38:52.760707 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 5 00:38:52.760848 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 5 00:38:52.762244 systemd[1]: Stopped target network.target - Network. Sep 5 00:38:52.763817 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 5 00:38:52.763895 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 5 00:38:52.765750 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 5 00:38:52.765806 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 5 00:38:52.767634 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 5 00:38:52.767707 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 5 00:38:52.768685 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 5 00:38:52.768746 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 5 00:38:52.769309 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 5 00:38:52.772280 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 5 00:38:52.780215 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 5 00:38:52.780443 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 5 00:38:52.786954 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 5 00:38:52.787329 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 5 00:38:52.787404 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:38:52.793310 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 5 00:38:52.797184 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 5 00:38:52.797854 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 5 00:38:52.798002 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 5 00:38:52.801097 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 5 00:38:52.801256 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 5 00:38:52.802528 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 5 00:38:52.802571 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:38:52.818745 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 5 00:38:52.820414 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 5 00:38:52.820501 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 00:38:52.822762 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 5 00:38:52.822819 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:38:52.828242 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 5 00:38:52.828321 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 5 00:38:52.829380 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:38:52.830912 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 5 00:38:52.853996 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 5 00:38:52.854277 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:38:52.858554 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 5 00:38:52.858721 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 5 00:38:52.859573 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 5 00:38:52.859671 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 5 00:38:52.863620 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 5 00:38:52.863671 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:38:52.864897 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 5 00:38:52.864974 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 5 00:38:52.869288 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 5 00:38:52.869374 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 5 00:38:52.872380 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 5 00:38:52.872478 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:38:52.880285 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 5 00:38:52.880371 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 5 00:38:52.880530 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 00:38:52.886459 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 5 00:38:52.886557 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:38:52.889971 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 5 00:38:52.890036 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:38:52.924050 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 5 00:38:52.924187 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:38:52.926497 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:38:52.926563 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:52.946108 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 5 00:38:52.946264 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 5 00:38:52.998353 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 5 00:38:52.998551 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 5 00:38:53.001564 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 5 00:38:53.003527 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 5 00:38:53.003623 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 5 00:38:53.006590 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 5 00:38:53.035197 systemd[1]: Switching root. Sep 5 00:38:53.079241 systemd-journald[219]: Journal stopped Sep 5 00:38:54.887215 systemd-journald[219]: Received SIGTERM from PID 1 (systemd). Sep 5 00:38:54.887281 kernel: SELinux: policy capability network_peer_controls=1 Sep 5 00:38:54.887296 kernel: SELinux: policy capability open_perms=1 Sep 5 00:38:54.887307 kernel: SELinux: policy capability extended_socket_class=1 Sep 5 00:38:54.887319 kernel: SELinux: policy capability always_check_network=0 Sep 5 00:38:54.887330 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 5 00:38:54.887345 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 5 00:38:54.887357 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 5 00:38:54.887368 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 5 00:38:54.887382 kernel: SELinux: policy capability userspace_initial_context=0 Sep 5 00:38:54.887403 kernel: audit: type=1403 audit(1757032733.631:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 5 00:38:54.887416 systemd[1]: Successfully loaded SELinux policy in 98.484ms. Sep 5 00:38:54.887440 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.763ms. Sep 5 00:38:54.887480 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 5 00:38:54.887494 systemd[1]: Detected virtualization kvm. Sep 5 00:38:54.887509 systemd[1]: Detected architecture x86-64. Sep 5 00:38:54.887521 systemd[1]: Detected first boot. Sep 5 00:38:54.887534 systemd[1]: Initializing machine ID from VM UUID. Sep 5 00:38:54.887551 zram_generator::config[1125]: No configuration found. Sep 5 00:38:54.887565 kernel: Guest personality initialized and is inactive Sep 5 00:38:54.887577 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 5 00:38:54.887588 kernel: Initialized host personality Sep 5 00:38:54.887600 kernel: NET: Registered PF_VSOCK protocol family Sep 5 00:38:54.887615 systemd[1]: Populated /etc with preset unit settings. Sep 5 00:38:54.887629 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 5 00:38:54.887642 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 5 00:38:54.887654 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 5 00:38:54.887667 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 5 00:38:54.887679 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 5 00:38:54.887692 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 5 00:38:54.887704 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 5 00:38:54.887717 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 5 00:38:54.887732 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 5 00:38:54.887745 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 5 00:38:54.887757 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 5 00:38:54.887769 systemd[1]: Created slice user.slice - User and Session Slice. Sep 5 00:38:54.887781 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:38:54.887794 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:38:54.887806 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 5 00:38:54.887818 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 5 00:38:54.887831 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 5 00:38:54.887846 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 00:38:54.887858 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 5 00:38:54.887871 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:38:54.887883 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:38:54.887895 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 5 00:38:54.887907 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 5 00:38:54.887920 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 5 00:38:54.887940 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 5 00:38:54.887952 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:38:54.887965 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 00:38:54.887979 systemd[1]: Reached target slices.target - Slice Units. Sep 5 00:38:54.887992 systemd[1]: Reached target swap.target - Swaps. Sep 5 00:38:54.888012 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 5 00:38:54.888024 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 5 00:38:54.888037 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 5 00:38:54.888049 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:38:54.888062 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 00:38:54.888079 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:38:54.888094 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 5 00:38:54.888110 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 5 00:38:54.888126 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 5 00:38:54.888139 systemd[1]: Mounting media.mount - External Media Directory... Sep 5 00:38:54.888151 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:54.888163 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 5 00:38:54.888182 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 5 00:38:54.888200 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 5 00:38:54.888213 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 5 00:38:54.888225 systemd[1]: Reached target machines.target - Containers. Sep 5 00:38:54.888237 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 5 00:38:54.888249 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:38:54.888261 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 00:38:54.888273 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 5 00:38:54.888286 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:38:54.888298 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 00:38:54.888315 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:38:54.888328 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 5 00:38:54.888340 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:38:54.888353 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 5 00:38:54.888365 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 5 00:38:54.888377 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 5 00:38:54.888390 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 5 00:38:54.888402 systemd[1]: Stopped systemd-fsck-usr.service. Sep 5 00:38:54.888420 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 00:38:54.888433 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 00:38:54.888471 kernel: loop: module loaded Sep 5 00:38:54.888485 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 00:38:54.888497 kernel: fuse: init (API version 7.41) Sep 5 00:38:54.888509 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 5 00:38:54.888522 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 5 00:38:54.888534 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 5 00:38:54.888546 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 00:38:54.888564 systemd[1]: verity-setup.service: Deactivated successfully. Sep 5 00:38:54.888576 systemd[1]: Stopped verity-setup.service. Sep 5 00:38:54.888590 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:54.888602 kernel: ACPI: bus type drm_connector registered Sep 5 00:38:54.888615 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 5 00:38:54.888629 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 5 00:38:54.888642 systemd[1]: Mounted media.mount - External Media Directory. Sep 5 00:38:54.888654 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 5 00:38:54.888669 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 5 00:38:54.888683 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 5 00:38:54.888699 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:38:54.888713 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 5 00:38:54.888726 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 5 00:38:54.888738 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:38:54.888751 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:38:54.888763 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 00:38:54.888776 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 00:38:54.888788 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:38:54.888800 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:38:54.888815 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 5 00:38:54.888828 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 5 00:38:54.888840 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:38:54.888853 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:38:54.888865 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 00:38:54.888878 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 00:38:54.888890 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 5 00:38:54.888903 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 5 00:38:54.888940 systemd-journald[1189]: Collecting audit messages is disabled. Sep 5 00:38:54.888964 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 5 00:38:54.888977 systemd-journald[1189]: Journal started Sep 5 00:38:54.889002 systemd-journald[1189]: Runtime Journal (/run/log/journal/972009bddbbe4a9b9ede0a6eebf3bd5d) is 6M, max 48.6M, 42.5M free. Sep 5 00:38:54.237953 systemd[1]: Queued start job for default target multi-user.target. Sep 5 00:38:54.258013 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 5 00:38:54.258601 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 5 00:38:54.890467 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 5 00:38:54.893507 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 5 00:38:54.896857 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 5 00:38:54.896893 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 00:38:54.899580 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 5 00:38:54.915937 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 5 00:38:54.921505 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:38:55.050901 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 5 00:38:55.054490 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 00:38:55.064522 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 5 00:38:55.067479 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 00:38:55.100507 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 00:38:55.112634 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 5 00:38:55.159496 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 5 00:38:55.162590 kernel: loop0: detected capacity change from 0 to 111000 Sep 5 00:38:55.162697 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 00:38:55.165979 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:38:55.167702 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 5 00:38:55.170401 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 5 00:38:55.192577 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 5 00:38:55.228223 systemd-journald[1189]: Time spent on flushing to /var/log/journal/972009bddbbe4a9b9ede0a6eebf3bd5d is 13.149ms for 971 entries. Sep 5 00:38:55.228223 systemd-journald[1189]: System Journal (/var/log/journal/972009bddbbe4a9b9ede0a6eebf3bd5d) is 8M, max 195.6M, 187.6M free. Sep 5 00:38:56.305416 systemd-journald[1189]: Received client request to flush runtime journal. Sep 5 00:38:56.305506 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 5 00:38:56.305540 kernel: loop1: detected capacity change from 0 to 128016 Sep 5 00:38:56.305560 kernel: loop2: detected capacity change from 0 to 229808 Sep 5 00:38:56.305581 kernel: loop3: detected capacity change from 0 to 111000 Sep 5 00:38:56.305599 kernel: loop4: detected capacity change from 0 to 128016 Sep 5 00:38:56.305618 kernel: loop5: detected capacity change from 0 to 229808 Sep 5 00:38:56.305634 zram_generator::config[1290]: No configuration found. Sep 5 00:38:55.236631 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:38:55.242308 systemd-tmpfiles[1225]: ACLs are not supported, ignoring. Sep 5 00:38:55.242322 systemd-tmpfiles[1225]: ACLs are not supported, ignoring. Sep 5 00:38:55.249337 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:38:55.319957 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 5 00:38:55.440849 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 5 00:38:55.628316 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 5 00:38:55.629764 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 5 00:38:55.632231 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 5 00:38:55.796781 (sd-merge)[1262]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 5 00:38:55.797548 (sd-merge)[1262]: Merged extensions into '/usr'. Sep 5 00:38:55.804261 systemd[1]: Reload requested from client PID 1222 ('systemd-sysext') (unit systemd-sysext.service)... Sep 5 00:38:55.804282 systemd[1]: Reloading... Sep 5 00:38:56.120074 systemd[1]: Reloading finished in 315 ms. Sep 5 00:38:56.150350 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 5 00:38:56.152147 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 5 00:38:56.168924 systemd[1]: Starting ensure-sysext.service... Sep 5 00:38:56.171007 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 00:38:56.195506 systemd-tmpfiles[1329]: ACLs are not supported, ignoring. Sep 5 00:38:56.195525 systemd-tmpfiles[1329]: ACLs are not supported, ignoring. Sep 5 00:38:56.200736 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:38:56.230271 systemd[1]: Reload requested from client PID 1328 ('systemctl') (unit ensure-sysext.service)... Sep 5 00:38:56.230284 systemd[1]: Reloading... Sep 5 00:38:56.312064 ldconfig[1207]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 5 00:38:56.347827 zram_generator::config[1356]: No configuration found. Sep 5 00:38:56.502661 systemd[1]: Reloading finished in 271 ms. Sep 5 00:38:56.529311 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 5 00:38:56.543524 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 5 00:38:56.556551 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 5 00:38:56.563693 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 5 00:38:56.586859 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 00:38:56.627821 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:56.628025 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:38:56.641950 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:38:56.658476 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:38:56.661964 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:38:56.663501 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:38:56.663748 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 00:38:56.664160 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:56.668195 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:38:56.674753 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:38:56.677625 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:38:56.677852 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:38:56.679444 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:38:56.679716 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:38:56.686376 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:56.686714 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:38:56.688130 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:38:56.731925 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:38:56.734804 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:38:56.735959 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:38:56.736072 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 00:38:56.736202 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:56.739894 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:56.740182 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:38:56.743091 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 00:38:56.752522 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:38:56.752802 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 00:38:56.752991 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:38:56.754491 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:38:56.754744 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:38:56.756497 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:38:56.756729 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:38:56.758519 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:38:56.758758 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:38:56.760271 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 00:38:56.760505 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 00:38:56.765793 systemd[1]: Finished ensure-sysext.service. Sep 5 00:38:56.783029 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 00:38:56.783120 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 00:38:56.884139 systemd-tmpfiles[1398]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 5 00:38:56.884401 systemd-tmpfiles[1398]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 5 00:38:56.884809 systemd-tmpfiles[1398]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 5 00:38:56.885136 systemd-tmpfiles[1398]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 5 00:38:56.886233 systemd-tmpfiles[1398]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 5 00:38:56.886596 systemd-tmpfiles[1398]: ACLs are not supported, ignoring. Sep 5 00:38:56.886689 systemd-tmpfiles[1398]: ACLs are not supported, ignoring. Sep 5 00:38:56.892379 systemd-tmpfiles[1398]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 00:38:56.892393 systemd-tmpfiles[1398]: Skipping /boot Sep 5 00:38:56.906929 systemd-tmpfiles[1398]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 00:38:56.906955 systemd-tmpfiles[1398]: Skipping /boot Sep 5 00:38:56.935212 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 5 00:38:56.938928 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:38:56.977372 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:38:56.995474 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 5 00:38:56.999293 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 5 00:38:57.006822 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 5 00:38:57.012182 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 00:38:57.017710 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 5 00:38:57.022094 systemd-udevd[1416]: Using default interface naming scheme 'v255'. Sep 5 00:38:57.053354 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 5 00:38:57.058566 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 5 00:38:57.178163 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 5 00:38:57.205597 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 5 00:38:57.208025 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:38:57.210487 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 5 00:38:57.221264 augenrules[1462]: No rules Sep 5 00:38:57.227214 systemd[1]: audit-rules.service: Deactivated successfully. Sep 5 00:38:57.227618 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 5 00:38:57.237681 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 00:38:57.242239 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 5 00:38:57.267155 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 5 00:38:57.272285 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 5 00:38:57.285607 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 5 00:38:57.394350 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 5 00:38:57.424726 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 5 00:38:57.426956 systemd[1]: Reached target time-set.target - System Time Set. Sep 5 00:38:57.427337 systemd-resolved[1422]: Positive Trust Anchors: Sep 5 00:38:57.427740 systemd-resolved[1422]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 00:38:57.427784 systemd-resolved[1422]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 5 00:38:57.435788 systemd-resolved[1422]: Defaulting to hostname 'linux'. Sep 5 00:38:57.438041 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 00:38:57.441841 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:38:57.443637 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 00:38:57.445505 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 5 00:38:57.447296 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 5 00:38:57.450529 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 5 00:38:57.452243 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 5 00:38:57.453698 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 5 00:38:57.455436 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 5 00:38:57.457181 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 5 00:38:57.457231 systemd[1]: Reached target paths.target - Path Units. Sep 5 00:38:57.458688 systemd[1]: Reached target timers.target - Timer Units. Sep 5 00:38:57.461498 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 5 00:38:57.466948 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 5 00:38:57.471064 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 5 00:38:57.472081 systemd-networkd[1477]: lo: Link UP Sep 5 00:38:57.472096 systemd-networkd[1477]: lo: Gained carrier Sep 5 00:38:57.473063 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 5 00:38:57.474809 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 5 00:38:57.475368 systemd-networkd[1477]: Enumeration completed Sep 5 00:38:57.477681 systemd-networkd[1477]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:38:57.477696 systemd-networkd[1477]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:38:57.479659 systemd-networkd[1477]: eth0: Link UP Sep 5 00:38:57.479914 systemd-networkd[1477]: eth0: Gained carrier Sep 5 00:38:57.479958 systemd-networkd[1477]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:38:57.480476 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 5 00:38:57.483363 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 5 00:38:57.485767 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 00:38:57.487238 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 5 00:38:57.497534 systemd-networkd[1477]: eth0: DHCPv4 address 10.0.0.129/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 5 00:38:57.498183 systemd-timesyncd[1423]: Network configuration changed, trying to establish connection. Sep 5 00:38:58.208011 systemd-timesyncd[1423]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 5 00:38:58.208072 systemd-timesyncd[1423]: Initial clock synchronization to Fri 2025-09-05 00:38:58.207920 UTC. Sep 5 00:38:58.208460 systemd-resolved[1422]: Clock change detected. Flushing caches. Sep 5 00:38:58.209315 kernel: mousedev: PS/2 mouse device common for all mice Sep 5 00:38:58.217012 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 5 00:38:58.218452 systemd[1]: Reached target network.target - Network. Sep 5 00:38:58.219470 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 00:38:58.220520 systemd[1]: Reached target basic.target - Basic System. Sep 5 00:38:58.221722 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 5 00:38:58.221778 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 5 00:38:58.227814 systemd[1]: Starting containerd.service - containerd container runtime... Sep 5 00:38:58.230196 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 5 00:38:58.233632 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 5 00:38:58.234301 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 Sep 5 00:38:58.243633 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 5 00:38:58.246315 kernel: ACPI: button: Power Button [PWRF] Sep 5 00:38:58.246857 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 5 00:38:58.247952 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 5 00:38:58.251460 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 5 00:38:58.255327 jq[1506]: false Sep 5 00:38:58.273525 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 5 00:38:58.284540 google_oslogin_nss_cache[1508]: oslogin_cache_refresh[1508]: Refreshing passwd entry cache Sep 5 00:38:58.284555 oslogin_cache_refresh[1508]: Refreshing passwd entry cache Sep 5 00:38:58.290631 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 5 00:38:58.296794 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 5 00:38:58.297043 extend-filesystems[1507]: Found /dev/vda6 Sep 5 00:38:58.299226 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 5 00:38:58.303483 oslogin_cache_refresh[1508]: Failure getting users, quitting Sep 5 00:38:58.303958 google_oslogin_nss_cache[1508]: oslogin_cache_refresh[1508]: Failure getting users, quitting Sep 5 00:38:58.303958 google_oslogin_nss_cache[1508]: oslogin_cache_refresh[1508]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 5 00:38:58.303958 google_oslogin_nss_cache[1508]: oslogin_cache_refresh[1508]: Refreshing group entry cache Sep 5 00:38:58.303432 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 5 00:38:58.303506 oslogin_cache_refresh[1508]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 5 00:38:58.303568 oslogin_cache_refresh[1508]: Refreshing group entry cache Sep 5 00:38:58.308571 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 5 00:38:58.312294 extend-filesystems[1507]: Found /dev/vda9 Sep 5 00:38:58.313563 google_oslogin_nss_cache[1508]: oslogin_cache_refresh[1508]: Failure getting groups, quitting Sep 5 00:38:58.313563 google_oslogin_nss_cache[1508]: oslogin_cache_refresh[1508]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 5 00:38:58.312891 oslogin_cache_refresh[1508]: Failure getting groups, quitting Sep 5 00:38:58.312911 oslogin_cache_refresh[1508]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 5 00:38:58.315492 extend-filesystems[1507]: Checking size of /dev/vda9 Sep 5 00:38:58.317828 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 5 00:38:58.323686 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 5 00:38:58.334621 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 5 00:38:58.337309 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 5 00:38:58.338137 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 5 00:38:58.340641 systemd[1]: Starting update-engine.service - Update Engine... Sep 5 00:38:58.348476 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 5 00:38:58.356061 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 5 00:38:58.356606 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 5 00:38:58.357029 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 5 00:38:58.357444 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 5 00:38:58.357829 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 5 00:38:58.359360 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 5 00:38:58.368582 jq[1544]: true Sep 5 00:38:58.368782 extend-filesystems[1507]: Resized partition /dev/vda9 Sep 5 00:38:58.359692 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 5 00:38:58.369009 extend-filesystems[1553]: resize2fs 1.47.2 (1-Jan-2025) Sep 5 00:38:58.384290 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 5 00:38:58.386972 jq[1554]: true Sep 5 00:38:58.401362 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 5 00:38:58.416354 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 5 00:38:58.415719 systemd[1]: motdgen.service: Deactivated successfully. Sep 5 00:38:58.434778 dbus-daemon[1504]: [system] SELinux support is enabled Sep 5 00:38:58.438714 update_engine[1540]: I20250905 00:38:58.421355 1540 main.cc:92] Flatcar Update Engine starting Sep 5 00:38:58.437600 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 5 00:38:58.439442 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 5 00:38:58.440181 extend-filesystems[1553]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 5 00:38:58.440181 extend-filesystems[1553]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 5 00:38:58.440181 extend-filesystems[1553]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 5 00:38:58.445299 extend-filesystems[1507]: Resized filesystem in /dev/vda9 Sep 5 00:38:58.444867 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 5 00:38:58.448444 update_engine[1540]: I20250905 00:38:58.442677 1540 update_check_scheduler.cc:74] Next update check in 8m30s Sep 5 00:38:58.445260 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 5 00:38:58.448840 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 5 00:38:58.457598 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 5 00:38:58.457666 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 5 00:38:58.459457 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 5 00:38:58.459483 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 5 00:38:58.466674 systemd[1]: Started update-engine.service - Update Engine. Sep 5 00:38:58.470813 (ntainerd)[1563]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 5 00:38:58.476573 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 5 00:38:58.590775 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:38:58.594661 systemd-logind[1535]: New seat seat0. Sep 5 00:38:58.596629 systemd[1]: Started systemd-logind.service - User Login Management. Sep 5 00:38:58.621941 kernel: kvm_amd: TSC scaling supported Sep 5 00:38:58.622054 kernel: kvm_amd: Nested Virtualization enabled Sep 5 00:38:58.622068 kernel: kvm_amd: Nested Paging enabled Sep 5 00:38:58.622378 kernel: kvm_amd: LBR virtualization supported Sep 5 00:38:58.623552 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 5 00:38:58.623597 kernel: kvm_amd: Virtual GIF supported Sep 5 00:38:58.638630 sshd_keygen[1560]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 5 00:38:58.678181 systemd-logind[1535]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 5 00:38:58.678389 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 5 00:38:58.690613 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 5 00:38:58.697921 systemd-logind[1535]: Watching system buttons on /dev/input/event2 (Power Button) Sep 5 00:38:58.707731 systemd[1]: issuegen.service: Deactivated successfully. Sep 5 00:38:58.708045 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 5 00:38:58.741232 bash[1582]: Updated "/home/core/.ssh/authorized_keys" Sep 5 00:38:58.751631 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 5 00:38:58.778646 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 5 00:38:58.781886 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 5 00:38:58.788803 kernel: EDAC MC: Ver: 3.0.0 Sep 5 00:38:58.814073 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 5 00:38:58.826535 locksmithd[1575]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 5 00:38:58.849513 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 5 00:38:58.865394 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 5 00:38:58.881842 systemd[1]: Reached target getty.target - Login Prompts. Sep 5 00:38:58.912186 containerd[1563]: time="2025-09-05T00:38:58Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 5 00:38:58.913398 containerd[1563]: time="2025-09-05T00:38:58.913364102Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 5 00:38:58.925634 containerd[1563]: time="2025-09-05T00:38:58.925549844Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.638µs" Sep 5 00:38:58.925634 containerd[1563]: time="2025-09-05T00:38:58.925596221Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 5 00:38:58.925634 containerd[1563]: time="2025-09-05T00:38:58.925619004Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 5 00:38:58.925975 containerd[1563]: time="2025-09-05T00:38:58.925932642Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 5 00:38:58.925975 containerd[1563]: time="2025-09-05T00:38:58.925956597Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 5 00:38:58.926044 containerd[1563]: time="2025-09-05T00:38:58.925991653Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 5 00:38:58.926134 containerd[1563]: time="2025-09-05T00:38:58.926093834Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 5 00:38:58.926134 containerd[1563]: time="2025-09-05T00:38:58.926115575Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 5 00:38:58.926522 containerd[1563]: time="2025-09-05T00:38:58.926475099Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 5 00:38:58.926522 containerd[1563]: time="2025-09-05T00:38:58.926495959Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 5 00:38:58.926522 containerd[1563]: time="2025-09-05T00:38:58.926509785Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 5 00:38:58.926522 containerd[1563]: time="2025-09-05T00:38:58.926520825Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 5 00:38:58.926737 containerd[1563]: time="2025-09-05T00:38:58.926688039Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 5 00:38:58.927064 containerd[1563]: time="2025-09-05T00:38:58.927019330Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 5 00:38:58.927109 containerd[1563]: time="2025-09-05T00:38:58.927064044Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 5 00:38:58.927109 containerd[1563]: time="2025-09-05T00:38:58.927077980Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 5 00:38:58.927182 containerd[1563]: time="2025-09-05T00:38:58.927142211Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 5 00:38:58.927459 containerd[1563]: time="2025-09-05T00:38:58.927409061Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 5 00:38:58.927535 containerd[1563]: time="2025-09-05T00:38:58.927505662Z" level=info msg="metadata content store policy set" policy=shared Sep 5 00:38:58.933498 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:38:59.065924 containerd[1563]: time="2025-09-05T00:38:59.065810052Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 5 00:38:59.065924 containerd[1563]: time="2025-09-05T00:38:59.065916332Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.065936610Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066012061Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066033862Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066047528Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066079708Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066100096Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066113702Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066126676Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066138508Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 5 00:38:59.066157 containerd[1563]: time="2025-09-05T00:38:59.066160109Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066414887Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066440144Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066459130Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066472645Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066487683Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066516707Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066532126Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066544920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066566531Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066581098Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066594263Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066684372Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 5 00:38:59.066688 containerd[1563]: time="2025-09-05T00:38:59.066702997Z" level=info msg="Start snapshots syncer" Sep 5 00:38:59.067018 containerd[1563]: time="2025-09-05T00:38:59.066753832Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 5 00:38:59.067130 containerd[1563]: time="2025-09-05T00:38:59.067051140Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 5 00:38:59.067332 containerd[1563]: time="2025-09-05T00:38:59.067138954Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 5 00:38:59.067332 containerd[1563]: time="2025-09-05T00:38:59.067221930Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 5 00:38:59.067406 containerd[1563]: time="2025-09-05T00:38:59.067376079Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.067400345Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.067812087Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.067888751Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.067997865Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068027010Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068055894Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068119223Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068144490Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068172823Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068242384Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068301935Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068326251Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068353412Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 5 00:38:59.068461 containerd[1563]: time="2025-09-05T00:38:59.068371726Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 5 00:38:59.068952 containerd[1563]: time="2025-09-05T00:38:59.068397424Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 5 00:38:59.068952 containerd[1563]: time="2025-09-05T00:38:59.068465412Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 5 00:38:59.068952 containerd[1563]: time="2025-09-05T00:38:59.068500598Z" level=info msg="runtime interface created" Sep 5 00:38:59.068952 containerd[1563]: time="2025-09-05T00:38:59.068509905Z" level=info msg="created NRI interface" Sep 5 00:38:59.068952 containerd[1563]: time="2025-09-05T00:38:59.068526757Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 5 00:38:59.068952 containerd[1563]: time="2025-09-05T00:38:59.068553327Z" level=info msg="Connect containerd service" Sep 5 00:38:59.068952 containerd[1563]: time="2025-09-05T00:38:59.068598922Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 5 00:38:59.070582 containerd[1563]: time="2025-09-05T00:38:59.070520456Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 5 00:38:59.263942 containerd[1563]: time="2025-09-05T00:38:59.263835030Z" level=info msg="Start subscribing containerd event" Sep 5 00:38:59.263942 containerd[1563]: time="2025-09-05T00:38:59.263931711Z" level=info msg="Start recovering state" Sep 5 00:38:59.264189 containerd[1563]: time="2025-09-05T00:38:59.264123441Z" level=info msg="Start event monitor" Sep 5 00:38:59.264189 containerd[1563]: time="2025-09-05T00:38:59.264149439Z" level=info msg="Start cni network conf syncer for default" Sep 5 00:38:59.264189 containerd[1563]: time="2025-09-05T00:38:59.264159729Z" level=info msg="Start streaming server" Sep 5 00:38:59.264189 containerd[1563]: time="2025-09-05T00:38:59.264181840Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 5 00:38:59.264189 containerd[1563]: time="2025-09-05T00:38:59.264191719Z" level=info msg="runtime interface starting up..." Sep 5 00:38:59.264340 containerd[1563]: time="2025-09-05T00:38:59.264201237Z" level=info msg="starting plugins..." Sep 5 00:38:59.264340 containerd[1563]: time="2025-09-05T00:38:59.264221795Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 5 00:38:59.264446 containerd[1563]: time="2025-09-05T00:38:59.264216826Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 5 00:38:59.264524 containerd[1563]: time="2025-09-05T00:38:59.264502441Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 5 00:38:59.264613 containerd[1563]: time="2025-09-05T00:38:59.264589875Z" level=info msg="containerd successfully booted in 0.353109s" Sep 5 00:38:59.264747 systemd[1]: Started containerd.service - containerd container runtime. Sep 5 00:38:59.547534 systemd-networkd[1477]: eth0: Gained IPv6LL Sep 5 00:38:59.551229 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 5 00:38:59.553098 systemd[1]: Reached target network-online.target - Network is Online. Sep 5 00:38:59.555743 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 5 00:38:59.558206 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 5 00:38:59.560778 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 5 00:38:59.597520 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 5 00:38:59.606057 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 5 00:38:59.606418 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 5 00:38:59.609770 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 5 00:39:00.760363 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 5 00:39:00.763238 systemd[1]: Started sshd@0-10.0.0.129:22-10.0.0.1:41952.service - OpenSSH per-connection server daemon (10.0.0.1:41952). Sep 5 00:39:00.830552 sshd[1656]: Accepted publickey for core from 10.0.0.1 port 41952 ssh2: RSA SHA256:FTPBIqxhuV7uWZ1wDxThX13wKyZS1sKik/rNT688yZo Sep 5 00:39:00.832976 sshd-session[1656]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:00.840482 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 5 00:39:00.843089 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 5 00:39:00.852653 systemd-logind[1535]: New session 1 of user core. Sep 5 00:39:00.874281 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 5 00:39:00.905468 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 5 00:39:01.005408 (systemd)[1661]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 5 00:39:01.008629 systemd-logind[1535]: New session c1 of user core. Sep 5 00:39:01.015628 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:39:01.020789 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 5 00:39:01.021173 (kubelet)[1668]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 5 00:39:01.161343 systemd[1661]: Queued start job for default target default.target. Sep 5 00:39:01.172614 systemd[1661]: Created slice app.slice - User Application Slice. Sep 5 00:39:01.172641 systemd[1661]: Reached target paths.target - Paths. Sep 5 00:39:01.172699 systemd[1661]: Reached target timers.target - Timers. Sep 5 00:39:01.174356 systemd[1661]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 5 00:39:01.190251 systemd[1661]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 5 00:39:01.190400 systemd[1661]: Reached target sockets.target - Sockets. Sep 5 00:39:01.190440 systemd[1661]: Reached target basic.target - Basic System. Sep 5 00:39:01.190480 systemd[1661]: Reached target default.target - Main User Target. Sep 5 00:39:01.190513 systemd[1661]: Startup finished in 170ms. Sep 5 00:39:01.191537 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 5 00:39:01.202465 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 5 00:39:01.223482 systemd[1]: Startup finished in 3.251s (kernel) + 6.947s (initrd) + 6.971s (userspace) = 17.170s. Sep 5 00:39:01.336755 systemd[1]: Started sshd@1-10.0.0.129:22-10.0.0.1:41956.service - OpenSSH per-connection server daemon (10.0.0.1:41956). Sep 5 00:39:01.400611 sshd[1687]: Accepted publickey for core from 10.0.0.1 port 41956 ssh2: RSA SHA256:FTPBIqxhuV7uWZ1wDxThX13wKyZS1sKik/rNT688yZo Sep 5 00:39:01.402616 sshd-session[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:01.408039 systemd-logind[1535]: New session 2 of user core. Sep 5 00:39:01.414894 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 5 00:39:01.475509 sshd[1690]: Connection closed by 10.0.0.1 port 41956 Sep 5 00:39:01.475427 sshd-session[1687]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:01.484494 systemd[1]: sshd@1-10.0.0.129:22-10.0.0.1:41956.service: Deactivated successfully. Sep 5 00:39:01.486646 systemd[1]: session-2.scope: Deactivated successfully. Sep 5 00:39:01.487605 systemd-logind[1535]: Session 2 logged out. Waiting for processes to exit. Sep 5 00:39:01.491982 systemd[1]: Started sshd@2-10.0.0.129:22-10.0.0.1:41960.service - OpenSSH per-connection server daemon (10.0.0.1:41960). Sep 5 00:39:01.495040 systemd-logind[1535]: Removed session 2. Sep 5 00:39:01.564014 sshd[1697]: Accepted publickey for core from 10.0.0.1 port 41960 ssh2: RSA SHA256:FTPBIqxhuV7uWZ1wDxThX13wKyZS1sKik/rNT688yZo Sep 5 00:39:01.566109 sshd-session[1697]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:01.571354 systemd-logind[1535]: New session 3 of user core. Sep 5 00:39:01.587692 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 5 00:39:01.640877 sshd[1700]: Connection closed by 10.0.0.1 port 41960 Sep 5 00:39:01.641594 sshd-session[1697]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:01.654926 systemd[1]: sshd@2-10.0.0.129:22-10.0.0.1:41960.service: Deactivated successfully. Sep 5 00:39:01.657217 systemd[1]: session-3.scope: Deactivated successfully. Sep 5 00:39:01.658589 systemd-logind[1535]: Session 3 logged out. Waiting for processes to exit. Sep 5 00:39:01.662340 systemd[1]: Started sshd@3-10.0.0.129:22-10.0.0.1:41974.service - OpenSSH per-connection server daemon (10.0.0.1:41974). Sep 5 00:39:01.663048 systemd-logind[1535]: Removed session 3. Sep 5 00:39:01.676935 kubelet[1668]: E0905 00:39:01.676878 1668 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Sep 5 00:39:01.681758 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 5 00:39:01.682102 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 5 00:39:01.682777 systemd[1]: kubelet.service: Consumed 1.860s CPU time, 267.4M memory peak. Sep 5 00:39:01.711471 sshd[1706]: Accepted publickey for core from 10.0.0.1 port 41974 ssh2: RSA SHA256:FTPBIqxhuV7uWZ1wDxThX13wKyZS1sKik/rNT688yZo Sep 5 00:39:01.713036 sshd-session[1706]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:01.718166 systemd-logind[1535]: New session 4 of user core. Sep 5 00:39:01.728451 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 5 00:39:01.785513 sshd[1710]: Connection closed by 10.0.0.1 port 41974 Sep 5 00:39:01.785988 sshd-session[1706]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:01.796009 systemd[1]: sshd@3-10.0.0.129:22-10.0.0.1:41974.service: Deactivated successfully. Sep 5 00:39:01.797861 systemd[1]: session-4.scope: Deactivated successfully. Sep 5 00:39:01.798666 systemd-logind[1535]: Session 4 logged out. Waiting for processes to exit. Sep 5 00:39:01.801514 systemd[1]: Started sshd@4-10.0.0.129:22-10.0.0.1:41980.service - OpenSSH per-connection server daemon (10.0.0.1:41980). Sep 5 00:39:01.802090 systemd-logind[1535]: Removed session 4. Sep 5 00:39:01.865560 sshd[1716]: Accepted publickey for core from 10.0.0.1 port 41980 ssh2: RSA SHA256:FTPBIqxhuV7uWZ1wDxThX13wKyZS1sKik/rNT688yZo Sep 5 00:39:01.867483 sshd-session[1716]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:39:01.872730 systemd-logind[1535]: New session 5 of user core. Sep 5 00:39:01.882436 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 5 00:39:01.944511 sudo[1721]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 5 00:39:01.944846 sudo[1721]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 00:39:01.960326 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 5 00:39:02.005516 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 5 00:39:02.005805 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 5 00:39:02.729128 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:39:02.729359 systemd[1]: kubelet.service: Consumed 1.860s CPU time, 267.4M memory peak. Sep 5 00:39:02.731935 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 5 00:39:02.759054 systemd[1]: Reload requested from client PID 1764 ('systemctl') (unit session-5.scope)... Sep 5 00:39:02.759068 systemd[1]: Reloading... Sep 5 00:39:02.836319 zram_generator::config[1808]: No configuration found. Sep 5 00:39:03.121404 systemd[1]: Reloading finished in 361 ms. Sep 5 00:39:03.192758 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 5 00:39:03.192918 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 5 00:39:03.193361 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:39:03.193430 systemd[1]: kubelet.service: Consumed 186ms CPU time, 98.3M memory peak. Sep 5 00:39:03.195505 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 5 00:39:03.399014 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:39:03.409665 (kubelet)[1853]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 5 00:39:03.460980 kubelet[1853]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 5 00:39:03.460980 kubelet[1853]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 5 00:39:03.460980 kubelet[1853]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 5 00:39:03.461612 kubelet[1853]: I0905 00:39:03.461052 1853 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" Sep 5 00:39:04.149952 kubelet[1853]: I0905 00:39:04.149876 1853 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Sep 5 00:39:04.149952 kubelet[1853]: I0905 00:39:04.149927 1853 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 5 00:39:04.150257 kubelet[1853]: I0905 00:39:04.150231 1853 server.go:956] "Client rotation is on, will bootstrap in background" Sep 5 00:39:04.871328 kubelet[1853]: I0905 00:39:04.871258 1853 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 5 00:39:04.878335 kubelet[1853]: I0905 00:39:04.878263 1853 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 5 00:39:04.884722 kubelet[1853]: I0905 00:39:04.884693 1853 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 5 00:39:04.885040 kubelet[1853]: I0905 00:39:04.884990 1853 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 5 00:39:04.885255 kubelet[1853]: I0905 00:39:04.885031 1853 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.129","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} Sep 5 00:39:04.885255 kubelet[1853]: I0905 00:39:04.885252 1853 topology_manager.go:138] "Creating topology manager with none policy" Sep 5 00:39:04.885456 kubelet[1853]: I0905 00:39:04.885282 1853 container_manager_linux.go:303] "Creating device plugin manager" Sep 5 00:39:04.886259 kubelet[1853]: I0905 00:39:04.886232 1853 state_mem.go:36] "Initialized new in-memory state store" Sep 5 00:39:04.888874 kubelet[1853]: I0905 00:39:04.888837 1853 kubelet.go:480] "Attempting to sync node with API server" Sep 5 00:39:04.888874 kubelet[1853]: I0905 00:39:04.888864 1853 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 5 00:39:04.888971 kubelet[1853]: I0905 00:39:04.888913 1853 kubelet.go:386] "Adding apiserver pod source" Sep 5 00:39:04.888971 kubelet[1853]: I0905 00:39:04.888940 1853 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 5 00:39:04.889033 kubelet[1853]: E0905 00:39:04.888992 1853 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:04.889073 kubelet[1853]: E0905 00:39:04.889047 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:04.894831 kubelet[1853]: I0905 00:39:04.894795 1853 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Sep 5 00:39:04.894947 kubelet[1853]: E0905 00:39:04.894917 1853 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.129\" 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" Sep 5 00:39:04.895138 kubelet[1853]: E0905 00:39:04.895102 1853 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" Sep 5 00:39:04.895686 kubelet[1853]: I0905 00:39:04.895649 1853 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 5 00:39:04.896320 kubelet[1853]: W0905 00:39:04.896261 1853 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 5 00:39:04.899684 kubelet[1853]: I0905 00:39:04.899660 1853 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 5 00:39:04.899736 kubelet[1853]: I0905 00:39:04.899711 1853 server.go:1289] "Started kubelet" Sep 5 00:39:04.899917 kubelet[1853]: I0905 00:39:04.899823 1853 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 5 00:39:04.900779 kubelet[1853]: I0905 00:39:04.900384 1853 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Sep 5 00:39:04.901545 kubelet[1853]: I0905 00:39:04.901498 1853 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 5 00:39:04.902765 kubelet[1853]: I0905 00:39:04.902720 1853 server.go:317] "Adding debug handlers to kubelet server" Sep 5 00:39:04.903253 kubelet[1853]: I0905 00:39:04.900395 1853 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 5 00:39:04.905159 kubelet[1853]: I0905 00:39:04.905124 1853 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 5 00:39:04.905599 kubelet[1853]: E0905 00:39:04.905407 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:04.905599 kubelet[1853]: I0905 00:39:04.905438 1853 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 5 00:39:04.905672 kubelet[1853]: I0905 00:39:04.905614 1853 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 5 00:39:04.905725 kubelet[1853]: I0905 00:39:04.905695 1853 reconciler.go:26] "Reconciler: start to sync state" Sep 5 00:39:04.906407 kubelet[1853]: E0905 00:39:04.906381 1853 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 5 00:39:04.906601 kubelet[1853]: I0905 00:39:04.906579 1853 factory.go:223] Registration of the systemd container factory successfully Sep 5 00:39:04.906697 kubelet[1853]: I0905 00:39:04.906677 1853 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 Sep 5 00:39:04.909644 kubelet[1853]: E0905 00:39:04.909583 1853 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.129\" not found" node="10.0.0.129" Sep 5 00:39:04.911708 kubelet[1853]: I0905 00:39:04.911664 1853 factory.go:223] Registration of the containerd container factory successfully Sep 5 00:39:04.928947 kubelet[1853]: I0905 00:39:04.928900 1853 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 5 00:39:04.928947 kubelet[1853]: I0905 00:39:04.928925 1853 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 5 00:39:04.928947 kubelet[1853]: I0905 00:39:04.928959 1853 state_mem.go:36] "Initialized new in-memory state store" Sep 5 00:39:04.932261 kubelet[1853]: I0905 00:39:04.932237 1853 policy_none.go:49] "None policy: Start" Sep 5 00:39:04.932358 kubelet[1853]: I0905 00:39:04.932263 1853 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 5 00:39:04.932358 kubelet[1853]: I0905 00:39:04.932310 1853 state_mem.go:35] "Initializing new in-memory state store" Sep 5 00:39:04.939932 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 5 00:39:04.954966 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 5 00:39:04.958851 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 5 00:39:04.966513 kubelet[1853]: E0905 00:39:04.966475 1853 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 5 00:39:04.966753 kubelet[1853]: I0905 00:39:04.966725 1853 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 5 00:39:04.966796 kubelet[1853]: I0905 00:39:04.966743 1853 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 5 00:39:04.968127 kubelet[1853]: I0905 00:39:04.967669 1853 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 5 00:39:04.969013 kubelet[1853]: E0905 00:39:04.968977 1853 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 5 00:39:04.969068 kubelet[1853]: E0905 00:39:04.969045 1853 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.129\" not found" Sep 5 00:39:04.980652 kubelet[1853]: I0905 00:39:04.980578 1853 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Sep 5 00:39:04.982599 kubelet[1853]: I0905 00:39:04.982542 1853 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Sep 5 00:39:04.982599 kubelet[1853]: I0905 00:39:04.982592 1853 status_manager.go:230] "Starting to sync pod status with apiserver" Sep 5 00:39:04.982685 kubelet[1853]: I0905 00:39:04.982618 1853 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 5 00:39:04.982685 kubelet[1853]: I0905 00:39:04.982629 1853 kubelet.go:2436] "Starting kubelet main sync loop" Sep 5 00:39:04.982685 kubelet[1853]: E0905 00:39:04.982678 1853 kubelet.go:2460] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 5 00:39:05.068339 kubelet[1853]: I0905 00:39:05.068287 1853 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.129" Sep 5 00:39:05.074402 kubelet[1853]: I0905 00:39:05.074342 1853 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.129" Sep 5 00:39:05.074402 kubelet[1853]: E0905 00:39:05.074388 1853 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.129\": node \"10.0.0.129\" not found" Sep 5 00:39:05.092795 kubelet[1853]: E0905 00:39:05.092719 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.158586 kubelet[1853]: I0905 00:39:05.158387 1853 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 5 00:39:05.158719 kubelet[1853]: I0905 00:39:05.158658 1853 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" Sep 5 00:39:05.158749 kubelet[1853]: I0905 00:39:05.158722 1853 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" Sep 5 00:39:05.193702 kubelet[1853]: E0905 00:39:05.193636 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.294673 kubelet[1853]: E0905 00:39:05.294611 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.394948 kubelet[1853]: E0905 00:39:05.394848 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.495882 kubelet[1853]: E0905 00:39:05.495791 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.596740 kubelet[1853]: E0905 00:39:05.596657 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.624068 sudo[1721]: pam_unix(sudo:session): session closed for user root Sep 5 00:39:05.626049 sshd[1720]: Connection closed by 10.0.0.1 port 41980 Sep 5 00:39:05.626463 sshd-session[1716]: pam_unix(sshd:session): session closed for user core Sep 5 00:39:05.631780 systemd[1]: sshd@4-10.0.0.129:22-10.0.0.1:41980.service: Deactivated successfully. Sep 5 00:39:05.634208 systemd[1]: session-5.scope: Deactivated successfully. Sep 5 00:39:05.634485 systemd[1]: session-5.scope: Consumed 766ms CPU time, 78.6M memory peak. Sep 5 00:39:05.635865 systemd-logind[1535]: Session 5 logged out. Waiting for processes to exit. Sep 5 00:39:05.637521 systemd-logind[1535]: Removed session 5. Sep 5 00:39:05.697547 kubelet[1853]: E0905 00:39:05.697460 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.798323 kubelet[1853]: E0905 00:39:05.798121 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:05.889333 kubelet[1853]: E0905 00:39:05.889194 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:05.899175 kubelet[1853]: E0905 00:39:05.899114 1853 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.129\" not found" Sep 5 00:39:06.000656 kubelet[1853]: I0905 00:39:06.000609 1853 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 5 00:39:06.000961 containerd[1563]: time="2025-09-05T00:39:06.000921612Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 5 00:39:06.001365 kubelet[1853]: I0905 00:39:06.001241 1853 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 5 00:39:06.889343 kubelet[1853]: I0905 00:39:06.889215 1853 apiserver.go:52] "Watching apiserver" Sep 5 00:39:06.889851 kubelet[1853]: E0905 00:39:06.889564 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:06.906184 kubelet[1853]: I0905 00:39:06.906126 1853 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 5 00:39:06.907325 systemd[1]: Created slice kubepods-besteffort-podf97028fa_d7ca_4f57_a2a5_1f167bf4ce70.slice - libcontainer container kubepods-besteffort-podf97028fa_d7ca_4f57_a2a5_1f167bf4ce70.slice. Sep 5 00:39:06.917108 kubelet[1853]: I0905 00:39:06.917036 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f97028fa-d7ca-4f57-a2a5-1f167bf4ce70-xtables-lock\") pod \"kube-proxy-wg5ks\" (UID: \"f97028fa-d7ca-4f57-a2a5-1f167bf4ce70\") " pod="kube-system/kube-proxy-wg5ks" Sep 5 00:39:06.917108 kubelet[1853]: I0905 00:39:06.917088 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/f97028fa-d7ca-4f57-a2a5-1f167bf4ce70-lib-modules\") pod \"kube-proxy-wg5ks\" (UID: \"f97028fa-d7ca-4f57-a2a5-1f167bf4ce70\") " pod="kube-system/kube-proxy-wg5ks" Sep 5 00:39:06.917108 kubelet[1853]: I0905 00:39:06.917121 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s84qt\" (UniqueName: \"kubernetes.io/projected/f97028fa-d7ca-4f57-a2a5-1f167bf4ce70-kube-api-access-s84qt\") pod \"kube-proxy-wg5ks\" (UID: \"f97028fa-d7ca-4f57-a2a5-1f167bf4ce70\") " pod="kube-system/kube-proxy-wg5ks" Sep 5 00:39:06.917346 kubelet[1853]: I0905 00:39:06.917160 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/e6fc474a-0030-4487-ae19-9f4c000590b0-cni-plugin\") pod \"kube-flannel-ds-ltrxc\" (UID: \"e6fc474a-0030-4487-ae19-9f4c000590b0\") " pod="kube-flannel/kube-flannel-ds-ltrxc" Sep 5 00:39:06.917346 kubelet[1853]: I0905 00:39:06.917183 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/e6fc474a-0030-4487-ae19-9f4c000590b0-flannel-cfg\") pod \"kube-flannel-ds-ltrxc\" (UID: \"e6fc474a-0030-4487-ae19-9f4c000590b0\") " pod="kube-flannel/kube-flannel-ds-ltrxc" Sep 5 00:39:06.917433 kubelet[1853]: I0905 00:39:06.917337 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bbnhg\" (UniqueName: \"kubernetes.io/projected/e6fc474a-0030-4487-ae19-9f4c000590b0-kube-api-access-bbnhg\") pod \"kube-flannel-ds-ltrxc\" (UID: \"e6fc474a-0030-4487-ae19-9f4c000590b0\") " pod="kube-flannel/kube-flannel-ds-ltrxc" Sep 5 00:39:06.917433 kubelet[1853]: I0905 00:39:06.917400 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/f97028fa-d7ca-4f57-a2a5-1f167bf4ce70-kube-proxy\") pod \"kube-proxy-wg5ks\" (UID: \"f97028fa-d7ca-4f57-a2a5-1f167bf4ce70\") " pod="kube-system/kube-proxy-wg5ks" Sep 5 00:39:06.917505 kubelet[1853]: I0905 00:39:06.917432 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/e6fc474a-0030-4487-ae19-9f4c000590b0-run\") pod \"kube-flannel-ds-ltrxc\" (UID: \"e6fc474a-0030-4487-ae19-9f4c000590b0\") " pod="kube-flannel/kube-flannel-ds-ltrxc" Sep 5 00:39:06.917505 kubelet[1853]: I0905 00:39:06.917472 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/e6fc474a-0030-4487-ae19-9f4c000590b0-cni\") pod \"kube-flannel-ds-ltrxc\" (UID: \"e6fc474a-0030-4487-ae19-9f4c000590b0\") " pod="kube-flannel/kube-flannel-ds-ltrxc" Sep 5 00:39:06.917549 kubelet[1853]: I0905 00:39:06.917535 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/e6fc474a-0030-4487-ae19-9f4c000590b0-xtables-lock\") pod \"kube-flannel-ds-ltrxc\" (UID: \"e6fc474a-0030-4487-ae19-9f4c000590b0\") " pod="kube-flannel/kube-flannel-ds-ltrxc" Sep 5 00:39:06.922346 systemd[1]: Created slice kubepods-burstable-pode6fc474a_0030_4487_ae19_9f4c000590b0.slice - libcontainer container kubepods-burstable-pode6fc474a_0030_4487_ae19_9f4c000590b0.slice. Sep 5 00:39:07.220677 containerd[1563]: time="2025-09-05T00:39:07.220596207Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wg5ks,Uid:f97028fa-d7ca-4f57-a2a5-1f167bf4ce70,Namespace:kube-system,Attempt:0,}" Sep 5 00:39:07.236561 containerd[1563]: time="2025-09-05T00:39:07.236498089Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ltrxc,Uid:e6fc474a-0030-4487-ae19-9f4c000590b0,Namespace:kube-flannel,Attempt:0,}" Sep 5 00:39:07.890764 kubelet[1853]: E0905 00:39:07.890662 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:08.477776 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1189929443.mount: Deactivated successfully. Sep 5 00:39:08.491777 containerd[1563]: time="2025-09-05T00:39:08.491706964Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:39:08.494811 containerd[1563]: time="2025-09-05T00:39:08.494763626Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" Sep 5 00:39:08.495987 containerd[1563]: time="2025-09-05T00:39:08.495942066Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:39:08.497152 containerd[1563]: time="2025-09-05T00:39:08.497120506Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:39:08.498036 containerd[1563]: time="2025-09-05T00:39:08.498006788Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 5 00:39:08.500068 containerd[1563]: time="2025-09-05T00:39:08.500032056Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:39:08.500733 containerd[1563]: time="2025-09-05T00:39:08.500698346Z" 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 1.084107398s" Sep 5 00:39:08.502763 containerd[1563]: time="2025-09-05T00:39:08.502445472Z" 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 1.088077854s" Sep 5 00:39:08.535943 containerd[1563]: time="2025-09-05T00:39:08.535875445Z" level=info msg="connecting to shim aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c" address="unix:///run/containerd/s/5e234e6ecbd86a0dc9121f01fa043d9c24bed9f6c7b898acd2000c452105ecb1" namespace=k8s.io protocol=ttrpc version=3 Sep 5 00:39:08.538168 containerd[1563]: time="2025-09-05T00:39:08.538124493Z" level=info msg="connecting to shim f93a8a5d3a999d758c36499b9bec0851ce418d261206667aa463cca334f733fd" address="unix:///run/containerd/s/feb1734d2a0fead30a796505cb206d4f01adb175c7a587c002af2ecfa4707c4d" namespace=k8s.io protocol=ttrpc version=3 Sep 5 00:39:08.567538 systemd[1]: Started cri-containerd-aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c.scope - libcontainer container aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c. Sep 5 00:39:08.578231 systemd[1]: Started cri-containerd-f93a8a5d3a999d758c36499b9bec0851ce418d261206667aa463cca334f733fd.scope - libcontainer container f93a8a5d3a999d758c36499b9bec0851ce418d261206667aa463cca334f733fd. Sep 5 00:39:08.611625 containerd[1563]: time="2025-09-05T00:39:08.611546035Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-wg5ks,Uid:f97028fa-d7ca-4f57-a2a5-1f167bf4ce70,Namespace:kube-system,Attempt:0,} returns sandbox id \"f93a8a5d3a999d758c36499b9bec0851ce418d261206667aa463cca334f733fd\"" Sep 5 00:39:08.613694 containerd[1563]: time="2025-09-05T00:39:08.613659268Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.4\"" Sep 5 00:39:08.622828 containerd[1563]: time="2025-09-05T00:39:08.622726211Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-ltrxc,Uid:e6fc474a-0030-4487-ae19-9f4c000590b0,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c\"" Sep 5 00:39:08.891391 kubelet[1853]: E0905 00:39:08.891149 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:09.891733 kubelet[1853]: E0905 00:39:09.891671 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:10.777441 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2048304014.mount: Deactivated successfully. Sep 5 00:39:10.892467 kubelet[1853]: E0905 00:39:10.892247 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:11.311973 containerd[1563]: time="2025-09-05T00:39:11.311889903Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:11.312780 containerd[1563]: time="2025-09-05T00:39:11.312742342Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.4: active requests=0, bytes read=31899626" Sep 5 00:39:11.313882 containerd[1563]: time="2025-09-05T00:39:11.313837486Z" level=info msg="ImageCreate event name:\"sha256:1b2ea5e018dbbbd2efb8e5c540a6d3c463d77f250d3904429402ee057f09c64e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:11.316042 containerd[1563]: time="2025-09-05T00:39:11.316000001Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:bb04e9247da3aaeb96406b4d530a79fc865695b6807353dd1a28871df0d7f837\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:11.316686 containerd[1563]: time="2025-09-05T00:39:11.316641825Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.4\" with image id \"sha256:1b2ea5e018dbbbd2efb8e5c540a6d3c463d77f250d3904429402ee057f09c64e\", repo tag \"registry.k8s.io/kube-proxy:v1.33.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:bb04e9247da3aaeb96406b4d530a79fc865695b6807353dd1a28871df0d7f837\", size \"31898645\" in 2.702944757s" Sep 5 00:39:11.316686 containerd[1563]: time="2025-09-05T00:39:11.316675548Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.4\" returns image reference \"sha256:1b2ea5e018dbbbd2efb8e5c540a6d3c463d77f250d3904429402ee057f09c64e\"" Sep 5 00:39:11.317683 containerd[1563]: time="2025-09-05T00:39:11.317491839Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 5 00:39:11.322015 containerd[1563]: time="2025-09-05T00:39:11.321982591Z" level=info msg="CreateContainer within sandbox \"f93a8a5d3a999d758c36499b9bec0851ce418d261206667aa463cca334f733fd\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 5 00:39:11.333085 containerd[1563]: time="2025-09-05T00:39:11.333041109Z" level=info msg="Container 64631e63062a9cd24a20ff5800c1141c043d084b3ddac1bd9d9c44b20d4fb414: CDI devices from CRI Config.CDIDevices: []" Sep 5 00:39:11.337633 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1846485955.mount: Deactivated successfully. Sep 5 00:39:11.344923 containerd[1563]: time="2025-09-05T00:39:11.344880623Z" level=info msg="CreateContainer within sandbox \"f93a8a5d3a999d758c36499b9bec0851ce418d261206667aa463cca334f733fd\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"64631e63062a9cd24a20ff5800c1141c043d084b3ddac1bd9d9c44b20d4fb414\"" Sep 5 00:39:11.345700 containerd[1563]: time="2025-09-05T00:39:11.345661427Z" level=info msg="StartContainer for \"64631e63062a9cd24a20ff5800c1141c043d084b3ddac1bd9d9c44b20d4fb414\"" Sep 5 00:39:11.347289 containerd[1563]: time="2025-09-05T00:39:11.347093693Z" level=info msg="connecting to shim 64631e63062a9cd24a20ff5800c1141c043d084b3ddac1bd9d9c44b20d4fb414" address="unix:///run/containerd/s/feb1734d2a0fead30a796505cb206d4f01adb175c7a587c002af2ecfa4707c4d" protocol=ttrpc version=3 Sep 5 00:39:11.380439 systemd[1]: Started cri-containerd-64631e63062a9cd24a20ff5800c1141c043d084b3ddac1bd9d9c44b20d4fb414.scope - libcontainer container 64631e63062a9cd24a20ff5800c1141c043d084b3ddac1bd9d9c44b20d4fb414. Sep 5 00:39:11.662082 containerd[1563]: time="2025-09-05T00:39:11.661957054Z" level=info msg="StartContainer for \"64631e63062a9cd24a20ff5800c1141c043d084b3ddac1bd9d9c44b20d4fb414\" returns successfully" Sep 5 00:39:11.892613 kubelet[1853]: E0905 00:39:11.892571 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:12.893083 kubelet[1853]: E0905 00:39:12.892999 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:13.893779 kubelet[1853]: E0905 00:39:13.893715 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:13.919986 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3718878642.mount: Deactivated successfully. Sep 5 00:39:13.990837 containerd[1563]: time="2025-09-05T00:39:13.990742398Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:13.992549 containerd[1563]: time="2025-09-05T00:39:13.992518659Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Sep 5 00:39:13.994807 containerd[1563]: time="2025-09-05T00:39:13.994759732Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:13.997770 containerd[1563]: time="2025-09-05T00:39:13.997697912Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:13.998974 containerd[1563]: time="2025-09-05T00:39:13.998924773Z" 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 2.681398499s" Sep 5 00:39:13.998974 containerd[1563]: time="2025-09-05T00:39:13.998960991Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Sep 5 00:39:14.008818 containerd[1563]: time="2025-09-05T00:39:14.008737255Z" level=info msg="CreateContainer within sandbox \"aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 5 00:39:14.022319 containerd[1563]: time="2025-09-05T00:39:14.022225941Z" level=info msg="Container 0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca: CDI devices from CRI Config.CDIDevices: []" Sep 5 00:39:14.026951 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2111151994.mount: Deactivated successfully. Sep 5 00:39:14.033861 containerd[1563]: time="2025-09-05T00:39:14.033779618Z" level=info msg="CreateContainer within sandbox \"aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca\"" Sep 5 00:39:14.034833 containerd[1563]: time="2025-09-05T00:39:14.034751380Z" level=info msg="StartContainer for \"0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca\"" Sep 5 00:39:14.039164 containerd[1563]: time="2025-09-05T00:39:14.039087642Z" level=info msg="connecting to shim 0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca" address="unix:///run/containerd/s/5e234e6ecbd86a0dc9121f01fa043d9c24bed9f6c7b898acd2000c452105ecb1" protocol=ttrpc version=3 Sep 5 00:39:14.075634 systemd[1]: Started cri-containerd-0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca.scope - libcontainer container 0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca. Sep 5 00:39:14.110484 systemd[1]: cri-containerd-0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca.scope: Deactivated successfully. Sep 5 00:39:14.113148 containerd[1563]: time="2025-09-05T00:39:14.113084253Z" level=info msg="TaskExit event in podsandbox handler container_id:\"0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca\" id:\"0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca\" pid:2192 exited_at:{seconds:1757032754 nanos:112577182}" Sep 5 00:39:14.203939 containerd[1563]: time="2025-09-05T00:39:14.203875058Z" level=info msg="received exit event container_id:\"0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca\" id:\"0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca\" pid:2192 exited_at:{seconds:1757032754 nanos:112577182}" Sep 5 00:39:14.205196 containerd[1563]: time="2025-09-05T00:39:14.205149267Z" level=info msg="StartContainer for \"0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca\" returns successfully" Sep 5 00:39:14.833307 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-0fac2b69b50dab2dc0cfc1f34425655b7fbe53bd691f2d65844b8f4f6cc2d4ca-rootfs.mount: Deactivated successfully. Sep 5 00:39:14.894003 kubelet[1853]: E0905 00:39:14.893958 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:15.166972 kubelet[1853]: I0905 00:39:15.166760 1853 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-wg5ks" podStartSLOduration=7.462533351 podStartE2EDuration="10.166741837s" podCreationTimestamp="2025-09-05 00:39:05 +0000 UTC" firstStartedPulling="2025-09-05 00:39:08.613123132 +0000 UTC m=+5.195590297" lastFinishedPulling="2025-09-05 00:39:11.317331618 +0000 UTC m=+7.899798783" observedRunningTime="2025-09-05 00:39:12.105574856 +0000 UTC m=+8.688042021" watchObservedRunningTime="2025-09-05 00:39:15.166741837 +0000 UTC m=+11.749209002" Sep 5 00:39:15.894196 kubelet[1853]: E0905 00:39:15.894106 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:16.007010 containerd[1563]: time="2025-09-05T00:39:16.006961418Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 5 00:39:16.894975 kubelet[1853]: E0905 00:39:16.894901 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:17.896135 kubelet[1853]: E0905 00:39:17.896042 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:18.713451 containerd[1563]: time="2025-09-05T00:39:18.713361504Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:18.714638 containerd[1563]: time="2025-09-05T00:39:18.714578377Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Sep 5 00:39:18.716613 containerd[1563]: time="2025-09-05T00:39:18.716480714Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:18.720225 containerd[1563]: time="2025-09-05T00:39:18.720174762Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:18.721911 containerd[1563]: time="2025-09-05T00:39:18.721840315Z" 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.714836137s" Sep 5 00:39:18.721911 containerd[1563]: time="2025-09-05T00:39:18.721904856Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Sep 5 00:39:18.727972 containerd[1563]: time="2025-09-05T00:39:18.727921430Z" level=info msg="CreateContainer within sandbox \"aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 5 00:39:18.739285 containerd[1563]: time="2025-09-05T00:39:18.739199540Z" level=info msg="Container 701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729: CDI devices from CRI Config.CDIDevices: []" Sep 5 00:39:18.753348 containerd[1563]: time="2025-09-05T00:39:18.753249819Z" level=info msg="CreateContainer within sandbox \"aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729\"" Sep 5 00:39:18.754083 containerd[1563]: time="2025-09-05T00:39:18.754026917Z" level=info msg="StartContainer for \"701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729\"" Sep 5 00:39:18.755690 containerd[1563]: time="2025-09-05T00:39:18.755651914Z" level=info msg="connecting to shim 701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729" address="unix:///run/containerd/s/5e234e6ecbd86a0dc9121f01fa043d9c24bed9f6c7b898acd2000c452105ecb1" protocol=ttrpc version=3 Sep 5 00:39:18.784552 systemd[1]: Started cri-containerd-701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729.scope - libcontainer container 701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729. Sep 5 00:39:18.896783 kubelet[1853]: E0905 00:39:18.896707 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:18.905902 systemd[1]: cri-containerd-701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729.scope: Deactivated successfully. Sep 5 00:39:18.907657 containerd[1563]: time="2025-09-05T00:39:18.907435042Z" level=warning msg="error from *cgroupsv2.Manager.EventChan" error="failed to add inotify watch for \"/sys/fs/cgroup/kubepods.slice/kubepods-burstable.slice/kubepods-burstable-pode6fc474a_0030_4487_ae19_9f4c000590b0.slice/cri-containerd-701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729.scope/memory.events\": no such file or directory" Sep 5 00:39:18.908000 containerd[1563]: time="2025-09-05T00:39:18.907933046Z" level=info msg="TaskExit event in podsandbox handler container_id:\"701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729\" id:\"701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729\" pid:2274 exited_at:{seconds:1757032758 nanos:907315047}" Sep 5 00:39:18.911002 containerd[1563]: time="2025-09-05T00:39:18.910934655Z" level=info msg="received exit event container_id:\"701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729\" id:\"701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729\" pid:2274 exited_at:{seconds:1757032758 nanos:907315047}" Sep 5 00:39:18.912434 containerd[1563]: time="2025-09-05T00:39:18.912393551Z" level=info msg="StartContainer for \"701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729\" returns successfully" Sep 5 00:39:18.938063 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-701811f182b69a778ba601d602c15d9fa4bfa177dc58537a352f9b5cfa981729-rootfs.mount: Deactivated successfully. Sep 5 00:39:18.985502 kubelet[1853]: I0905 00:39:18.985351 1853 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 5 00:39:19.897337 kubelet[1853]: E0905 00:39:19.897196 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:20.125301 containerd[1563]: time="2025-09-05T00:39:20.125198823Z" level=info msg="CreateContainer within sandbox \"aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 5 00:39:20.473783 containerd[1563]: time="2025-09-05T00:39:20.473714455Z" level=info msg="Container d1984b081474c0843dd7ad484d3388f2faf8e47fb134f543bd4ca4b439353e7b: CDI devices from CRI Config.CDIDevices: []" Sep 5 00:39:20.701764 containerd[1563]: time="2025-09-05T00:39:20.701716219Z" level=info msg="CreateContainer within sandbox \"aa67bdd554eb861dd20ff50d73b0ee9d477a73fe863cebdd912f8add852f202c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"d1984b081474c0843dd7ad484d3388f2faf8e47fb134f543bd4ca4b439353e7b\"" Sep 5 00:39:20.702391 containerd[1563]: time="2025-09-05T00:39:20.702357171Z" level=info msg="StartContainer for \"d1984b081474c0843dd7ad484d3388f2faf8e47fb134f543bd4ca4b439353e7b\"" Sep 5 00:39:20.703558 containerd[1563]: time="2025-09-05T00:39:20.703423461Z" level=info msg="connecting to shim d1984b081474c0843dd7ad484d3388f2faf8e47fb134f543bd4ca4b439353e7b" address="unix:///run/containerd/s/5e234e6ecbd86a0dc9121f01fa043d9c24bed9f6c7b898acd2000c452105ecb1" protocol=ttrpc version=3 Sep 5 00:39:20.727430 systemd[1]: Started cri-containerd-d1984b081474c0843dd7ad484d3388f2faf8e47fb134f543bd4ca4b439353e7b.scope - libcontainer container d1984b081474c0843dd7ad484d3388f2faf8e47fb134f543bd4ca4b439353e7b. Sep 5 00:39:20.824638 containerd[1563]: time="2025-09-05T00:39:20.824595331Z" level=info msg="StartContainer for \"d1984b081474c0843dd7ad484d3388f2faf8e47fb134f543bd4ca4b439353e7b\" returns successfully" Sep 5 00:39:20.897825 kubelet[1853]: E0905 00:39:20.897732 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:21.035919 kubelet[1853]: I0905 00:39:21.035730 1853 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-ltrxc" podStartSLOduration=5.936767565 podStartE2EDuration="16.035713784s" podCreationTimestamp="2025-09-05 00:39:05 +0000 UTC" firstStartedPulling="2025-09-05 00:39:08.624064331 +0000 UTC m=+5.206531496" lastFinishedPulling="2025-09-05 00:39:18.72301055 +0000 UTC m=+15.305477715" observedRunningTime="2025-09-05 00:39:21.035668409 +0000 UTC m=+17.618135584" watchObservedRunningTime="2025-09-05 00:39:21.035713784 +0000 UTC m=+17.618180939" Sep 5 00:39:21.823317 systemd-networkd[1477]: flannel.1: Link UP Sep 5 00:39:21.823342 systemd-networkd[1477]: flannel.1: Gained carrier Sep 5 00:39:21.897999 kubelet[1853]: E0905 00:39:21.897922 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:21.930829 systemd[1]: Created slice kubepods-besteffort-pode8717e0f_a887_463f_afa8_8655782b9256.slice - libcontainer container kubepods-besteffort-pode8717e0f_a887_463f_afa8_8655782b9256.slice. Sep 5 00:39:21.941053 kubelet[1853]: I0905 00:39:21.941014 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zzj64\" (UniqueName: \"kubernetes.io/projected/e8717e0f-a887-463f-afa8-8655782b9256-kube-api-access-zzj64\") pod \"nginx-deployment-7fcdb87857-8hdrk\" (UID: \"e8717e0f-a887-463f-afa8-8655782b9256\") " pod="default/nginx-deployment-7fcdb87857-8hdrk" Sep 5 00:39:22.235176 containerd[1563]: time="2025-09-05T00:39:22.235107896Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-8hdrk,Uid:e8717e0f-a887-463f-afa8-8655782b9256,Namespace:default,Attempt:0,}" Sep 5 00:39:22.250156 systemd-networkd[1477]: cni0: Link UP Sep 5 00:39:22.260727 systemd-networkd[1477]: veth4f47b271: Link UP Sep 5 00:39:22.262601 kernel: cni0: port 1(veth4f47b271) entered blocking state Sep 5 00:39:22.262843 kernel: cni0: port 1(veth4f47b271) entered disabled state Sep 5 00:39:22.262892 kernel: veth4f47b271: entered allmulticast mode Sep 5 00:39:22.264360 kernel: veth4f47b271: entered promiscuous mode Sep 5 00:39:22.274214 kernel: cni0: port 1(veth4f47b271) entered blocking state Sep 5 00:39:22.274307 kernel: cni0: port 1(veth4f47b271) entered forwarding state Sep 5 00:39:22.274414 systemd-networkd[1477]: veth4f47b271: Gained carrier Sep 5 00:39:22.275474 systemd-networkd[1477]: cni0: Gained carrier Sep 5 00:39:22.280698 containerd[1563]: 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"} Sep 5 00:39:22.280698 containerd[1563]: delegateAdd: netconf sent to delegate plugin: Sep 5 00:39:22.324076 containerd[1563]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-05T00:39:22.323992597Z" level=info msg="connecting to shim 7faf8d8bd5d13a3571f79f09be15c2748f81a6b081a6583a7b766c08c47f5565" address="unix:///run/containerd/s/d05253493c289e7a67727dde1a12001ad789f383bd607efb2e1c0539952663c7" namespace=k8s.io protocol=ttrpc version=3 Sep 5 00:39:22.355478 systemd[1]: Started cri-containerd-7faf8d8bd5d13a3571f79f09be15c2748f81a6b081a6583a7b766c08c47f5565.scope - libcontainer container 7faf8d8bd5d13a3571f79f09be15c2748f81a6b081a6583a7b766c08c47f5565. Sep 5 00:39:22.372516 systemd-resolved[1422]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 5 00:39:22.470890 containerd[1563]: time="2025-09-05T00:39:22.470804892Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-8hdrk,Uid:e8717e0f-a887-463f-afa8-8655782b9256,Namespace:default,Attempt:0,} returns sandbox id \"7faf8d8bd5d13a3571f79f09be15c2748f81a6b081a6583a7b766c08c47f5565\"" Sep 5 00:39:22.472091 containerd[1563]: time="2025-09-05T00:39:22.472027385Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 5 00:39:22.898497 kubelet[1853]: E0905 00:39:22.898431 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:23.483567 systemd-networkd[1477]: flannel.1: Gained IPv6LL Sep 5 00:39:23.484532 systemd-networkd[1477]: veth4f47b271: Gained IPv6LL Sep 5 00:39:23.899632 kubelet[1853]: E0905 00:39:23.899435 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:23.931472 systemd-networkd[1477]: cni0: Gained IPv6LL Sep 5 00:39:24.889383 kubelet[1853]: E0905 00:39:24.889296 1853 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:24.900120 kubelet[1853]: E0905 00:39:24.900045 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:25.900493 kubelet[1853]: E0905 00:39:25.900406 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:26.258633 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1152103564.mount: Deactivated successfully. Sep 5 00:39:26.901223 kubelet[1853]: E0905 00:39:26.901169 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:27.902352 kubelet[1853]: E0905 00:39:27.902251 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:28.717658 containerd[1563]: time="2025-09-05T00:39:28.717584123Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:28.719010 containerd[1563]: time="2025-09-05T00:39:28.718983958Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73307810" Sep 5 00:39:28.722112 containerd[1563]: time="2025-09-05T00:39:28.722079203Z" level=info msg="ImageCreate event name:\"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:28.725835 containerd[1563]: time="2025-09-05T00:39:28.725774563Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:28.726740 containerd[1563]: time="2025-09-05T00:39:28.726692314Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"73307688\" in 6.254609355s" Sep 5 00:39:28.726740 containerd[1563]: time="2025-09-05T00:39:28.726733100Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\"" Sep 5 00:39:28.733002 containerd[1563]: time="2025-09-05T00:39:28.732947264Z" level=info msg="CreateContainer within sandbox \"7faf8d8bd5d13a3571f79f09be15c2748f81a6b081a6583a7b766c08c47f5565\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 5 00:39:28.743557 containerd[1563]: time="2025-09-05T00:39:28.743513219Z" level=info msg="Container dee22ced1d2d01abfb2030b52b301c4fa4c6917a55f7bc34b8fa617f0895ee48: CDI devices from CRI Config.CDIDevices: []" Sep 5 00:39:28.747764 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2474918419.mount: Deactivated successfully. Sep 5 00:39:28.750447 containerd[1563]: time="2025-09-05T00:39:28.750383093Z" level=info msg="CreateContainer within sandbox \"7faf8d8bd5d13a3571f79f09be15c2748f81a6b081a6583a7b766c08c47f5565\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"dee22ced1d2d01abfb2030b52b301c4fa4c6917a55f7bc34b8fa617f0895ee48\"" Sep 5 00:39:28.751145 containerd[1563]: time="2025-09-05T00:39:28.751079318Z" level=info msg="StartContainer for \"dee22ced1d2d01abfb2030b52b301c4fa4c6917a55f7bc34b8fa617f0895ee48\"" Sep 5 00:39:28.752126 containerd[1563]: time="2025-09-05T00:39:28.752098369Z" level=info msg="connecting to shim dee22ced1d2d01abfb2030b52b301c4fa4c6917a55f7bc34b8fa617f0895ee48" address="unix:///run/containerd/s/d05253493c289e7a67727dde1a12001ad789f383bd607efb2e1c0539952663c7" protocol=ttrpc version=3 Sep 5 00:39:28.780451 systemd[1]: Started cri-containerd-dee22ced1d2d01abfb2030b52b301c4fa4c6917a55f7bc34b8fa617f0895ee48.scope - libcontainer container dee22ced1d2d01abfb2030b52b301c4fa4c6917a55f7bc34b8fa617f0895ee48. Sep 5 00:39:28.826321 containerd[1563]: time="2025-09-05T00:39:28.826259318Z" level=info msg="StartContainer for \"dee22ced1d2d01abfb2030b52b301c4fa4c6917a55f7bc34b8fa617f0895ee48\" returns successfully" Sep 5 00:39:28.903042 kubelet[1853]: E0905 00:39:28.902972 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:29.051048 kubelet[1853]: I0905 00:39:29.050871 1853 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-8hdrk" podStartSLOduration=1.795065186 podStartE2EDuration="8.050846489s" podCreationTimestamp="2025-09-05 00:39:21 +0000 UTC" firstStartedPulling="2025-09-05 00:39:22.471751938 +0000 UTC m=+19.054219103" lastFinishedPulling="2025-09-05 00:39:28.727533241 +0000 UTC m=+25.310000406" observedRunningTime="2025-09-05 00:39:29.050690316 +0000 UTC m=+25.633157481" watchObservedRunningTime="2025-09-05 00:39:29.050846489 +0000 UTC m=+25.633313654" Sep 5 00:39:29.904040 kubelet[1853]: E0905 00:39:29.903950 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:30.904678 kubelet[1853]: E0905 00:39:30.904601 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:31.905698 kubelet[1853]: E0905 00:39:31.905640 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:32.906307 kubelet[1853]: E0905 00:39:32.906211 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:33.763487 systemd[1]: Created slice kubepods-besteffort-pod764a73fe_330b_4a92_a9f9_6c084391166a.slice - libcontainer container kubepods-besteffort-pod764a73fe_330b_4a92_a9f9_6c084391166a.slice. Sep 5 00:39:33.813879 kubelet[1853]: I0905 00:39:33.813784 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-7wdps\" (UniqueName: \"kubernetes.io/projected/764a73fe-330b-4a92-a9f9-6c084391166a-kube-api-access-7wdps\") pod \"nfs-server-provisioner-0\" (UID: \"764a73fe-330b-4a92-a9f9-6c084391166a\") " pod="default/nfs-server-provisioner-0" Sep 5 00:39:33.813879 kubelet[1853]: I0905 00:39:33.813866 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/764a73fe-330b-4a92-a9f9-6c084391166a-data\") pod \"nfs-server-provisioner-0\" (UID: \"764a73fe-330b-4a92-a9f9-6c084391166a\") " pod="default/nfs-server-provisioner-0" Sep 5 00:39:33.907308 kubelet[1853]: E0905 00:39:33.907218 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:34.067104 containerd[1563]: time="2025-09-05T00:39:34.066954047Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:764a73fe-330b-4a92-a9f9-6c084391166a,Namespace:default,Attempt:0,}" Sep 5 00:39:34.282748 systemd-networkd[1477]: vethe704bfc9: Link UP Sep 5 00:39:34.289976 kernel: cni0: port 2(vethe704bfc9) entered blocking state Sep 5 00:39:34.290131 kernel: cni0: port 2(vethe704bfc9) entered disabled state Sep 5 00:39:34.290164 kernel: vethe704bfc9: entered allmulticast mode Sep 5 00:39:34.290191 kernel: vethe704bfc9: entered promiscuous mode Sep 5 00:39:34.297779 kernel: cni0: port 2(vethe704bfc9) entered blocking state Sep 5 00:39:34.297901 kernel: cni0: port 2(vethe704bfc9) entered forwarding state Sep 5 00:39:34.298007 systemd-networkd[1477]: vethe704bfc9: Gained carrier Sep 5 00:39:34.303978 containerd[1563]: 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"} Sep 5 00:39:34.303978 containerd[1563]: delegateAdd: netconf sent to delegate plugin: Sep 5 00:39:34.350585 containerd[1563]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-05T00:39:34.350418163Z" level=info msg="connecting to shim 0ab6acc0b7aa02c0df39540081681c2992d98731296d7b8a20d525d60429e4cb" address="unix:///run/containerd/s/b0a5a13e21b9a9b664498c3a8093b1afbfc1710fc4417ce4f97ec4fefd647c8b" namespace=k8s.io protocol=ttrpc version=3 Sep 5 00:39:34.382407 systemd[1]: Started cri-containerd-0ab6acc0b7aa02c0df39540081681c2992d98731296d7b8a20d525d60429e4cb.scope - libcontainer container 0ab6acc0b7aa02c0df39540081681c2992d98731296d7b8a20d525d60429e4cb. Sep 5 00:39:34.397394 systemd-resolved[1422]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 5 00:39:34.430894 containerd[1563]: time="2025-09-05T00:39:34.430829732Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:764a73fe-330b-4a92-a9f9-6c084391166a,Namespace:default,Attempt:0,} returns sandbox id \"0ab6acc0b7aa02c0df39540081681c2992d98731296d7b8a20d525d60429e4cb\"" Sep 5 00:39:34.433160 containerd[1563]: time="2025-09-05T00:39:34.433126213Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 5 00:39:34.907422 kubelet[1853]: E0905 00:39:34.907350 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:35.907829 kubelet[1853]: E0905 00:39:35.907728 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:35.963523 systemd-networkd[1477]: vethe704bfc9: Gained IPv6LL Sep 5 00:39:36.908476 kubelet[1853]: E0905 00:39:36.908422 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:36.977913 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount998686425.mount: Deactivated successfully. Sep 5 00:39:37.909206 kubelet[1853]: E0905 00:39:37.909138 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:38.909435 kubelet[1853]: E0905 00:39:38.909346 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:39.193321 containerd[1563]: time="2025-09-05T00:39:39.193121954Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:39.193992 containerd[1563]: time="2025-09-05T00:39:39.193949370Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Sep 5 00:39:39.195370 containerd[1563]: time="2025-09-05T00:39:39.195329561Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:39.198173 containerd[1563]: time="2025-09-05T00:39:39.198083879Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:39.199222 containerd[1563]: time="2025-09-05T00:39:39.199190076Z" 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.766019428s" Sep 5 00:39:39.199300 containerd[1563]: time="2025-09-05T00:39:39.199224131Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Sep 5 00:39:39.204304 containerd[1563]: time="2025-09-05T00:39:39.204257041Z" level=info msg="CreateContainer within sandbox \"0ab6acc0b7aa02c0df39540081681c2992d98731296d7b8a20d525d60429e4cb\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 5 00:39:39.213003 containerd[1563]: time="2025-09-05T00:39:39.212938322Z" level=info msg="Container df5a32aae4580c632236f2a25d12525de035988fc07604042af0ee7c0af139b8: CDI devices from CRI Config.CDIDevices: []" Sep 5 00:39:39.221220 containerd[1563]: time="2025-09-05T00:39:39.221168334Z" level=info msg="CreateContainer within sandbox \"0ab6acc0b7aa02c0df39540081681c2992d98731296d7b8a20d525d60429e4cb\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"df5a32aae4580c632236f2a25d12525de035988fc07604042af0ee7c0af139b8\"" Sep 5 00:39:39.222099 containerd[1563]: time="2025-09-05T00:39:39.222058160Z" level=info msg="StartContainer for \"df5a32aae4580c632236f2a25d12525de035988fc07604042af0ee7c0af139b8\"" Sep 5 00:39:39.223351 containerd[1563]: time="2025-09-05T00:39:39.223257325Z" level=info msg="connecting to shim df5a32aae4580c632236f2a25d12525de035988fc07604042af0ee7c0af139b8" address="unix:///run/containerd/s/b0a5a13e21b9a9b664498c3a8093b1afbfc1710fc4417ce4f97ec4fefd647c8b" protocol=ttrpc version=3 Sep 5 00:39:39.246400 systemd[1]: Started cri-containerd-df5a32aae4580c632236f2a25d12525de035988fc07604042af0ee7c0af139b8.scope - libcontainer container df5a32aae4580c632236f2a25d12525de035988fc07604042af0ee7c0af139b8. Sep 5 00:39:39.279812 containerd[1563]: time="2025-09-05T00:39:39.279685454Z" level=info msg="StartContainer for \"df5a32aae4580c632236f2a25d12525de035988fc07604042af0ee7c0af139b8\" returns successfully" Sep 5 00:39:39.910566 kubelet[1853]: E0905 00:39:39.910468 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:40.911366 kubelet[1853]: E0905 00:39:40.911258 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:41.912498 kubelet[1853]: E0905 00:39:41.912423 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:42.913667 kubelet[1853]: E0905 00:39:42.913557 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:43.914737 kubelet[1853]: E0905 00:39:43.914605 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:44.058549 update_engine[1540]: I20250905 00:39:44.058197 1540 update_attempter.cc:509] Updating boot flags... Sep 5 00:39:44.890233 kubelet[1853]: E0905 00:39:44.890115 1853 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:45.068702 kubelet[1853]: E0905 00:39:44.915711 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:45.226123 kubelet[1853]: I0905 00:39:45.225197 1853 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=7.457947329 podStartE2EDuration="12.225165636s" podCreationTimestamp="2025-09-05 00:39:33 +0000 UTC" firstStartedPulling="2025-09-05 00:39:34.43276438 +0000 UTC m=+31.015231655" lastFinishedPulling="2025-09-05 00:39:39.199982797 +0000 UTC m=+35.782449962" observedRunningTime="2025-09-05 00:39:40.076581385 +0000 UTC m=+36.659048560" watchObservedRunningTime="2025-09-05 00:39:45.225165636 +0000 UTC m=+41.807632801" Sep 5 00:39:45.250586 systemd[1]: Created slice kubepods-besteffort-podbae2120e_70bc_45e8_a11a_8523703edd06.slice - libcontainer container kubepods-besteffort-podbae2120e_70bc_45e8_a11a_8523703edd06.slice. Sep 5 00:39:45.680399 kubelet[1853]: I0905 00:39:45.680163 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9567g\" (UniqueName: \"kubernetes.io/projected/bae2120e-70bc-45e8-a11a-8523703edd06-kube-api-access-9567g\") pod \"test-pod-1\" (UID: \"bae2120e-70bc-45e8-a11a-8523703edd06\") " pod="default/test-pod-1" Sep 5 00:39:45.680399 kubelet[1853]: I0905 00:39:45.680233 1853 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-217c10c8-843d-4b5e-84d7-6119b197f06f\" (UniqueName: \"kubernetes.io/nfs/bae2120e-70bc-45e8-a11a-8523703edd06-pvc-217c10c8-843d-4b5e-84d7-6119b197f06f\") pod \"test-pod-1\" (UID: \"bae2120e-70bc-45e8-a11a-8523703edd06\") " pod="default/test-pod-1" Sep 5 00:39:45.820329 kernel: netfs: FS-Cache loaded Sep 5 00:39:45.901606 kernel: RPC: Registered named UNIX socket transport module. Sep 5 00:39:45.901798 kernel: RPC: Registered udp transport module. Sep 5 00:39:45.901823 kernel: RPC: Registered tcp transport module. Sep 5 00:39:45.902922 kernel: RPC: Registered tcp-with-tls transport module. Sep 5 00:39:45.902977 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 5 00:39:45.916299 kubelet[1853]: E0905 00:39:45.916211 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:46.168622 kernel: NFS: Registering the id_resolver key type Sep 5 00:39:46.168830 kernel: Key type id_resolver registered Sep 5 00:39:46.168894 kernel: Key type id_legacy registered Sep 5 00:39:46.213619 nfsidmap[2868]: 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 Sep 5 00:39:46.214347 nfsidmap[2868]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 5 00:39:46.216754 nfsidmap[2869]: 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 Sep 5 00:39:46.217015 nfsidmap[2869]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 5 00:39:46.226963 nfsrahead[2871]: setting /var/lib/kubelet/pods/bae2120e-70bc-45e8-a11a-8523703edd06/volumes/kubernetes.io~nfs/pvc-217c10c8-843d-4b5e-84d7-6119b197f06f readahead to 128 Sep 5 00:39:46.454650 containerd[1563]: time="2025-09-05T00:39:46.454592578Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:bae2120e-70bc-45e8-a11a-8523703edd06,Namespace:default,Attempt:0,}" Sep 5 00:39:46.545191 systemd-networkd[1477]: vethfe75ab72: Link UP Sep 5 00:39:46.547009 kernel: cni0: port 3(vethfe75ab72) entered blocking state Sep 5 00:39:46.547131 kernel: cni0: port 3(vethfe75ab72) entered disabled state Sep 5 00:39:46.547163 kernel: vethfe75ab72: entered allmulticast mode Sep 5 00:39:46.548767 kernel: vethfe75ab72: entered promiscuous mode Sep 5 00:39:46.558904 kernel: cni0: port 3(vethfe75ab72) entered blocking state Sep 5 00:39:46.559008 kernel: cni0: port 3(vethfe75ab72) entered forwarding state Sep 5 00:39:46.559217 systemd-networkd[1477]: vethfe75ab72: Gained carrier Sep 5 00:39:46.562864 containerd[1563]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a950), "name":"cbr0", "type":"bridge"} Sep 5 00:39:46.562864 containerd[1563]: delegateAdd: netconf sent to delegate plugin: Sep 5 00:39:46.602126 containerd[1563]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-05T00:39:46.602056249Z" level=info msg="connecting to shim eb06b516045584f145b2b139db72ec5f2b2ac249d6a3c93867de0625c64b4d17" address="unix:///run/containerd/s/be6dd20a1f63401d69143c6ec4a3e3806d8af6dc765959269e5efdfeab3f69ba" namespace=k8s.io protocol=ttrpc version=3 Sep 5 00:39:46.648611 systemd[1]: Started cri-containerd-eb06b516045584f145b2b139db72ec5f2b2ac249d6a3c93867de0625c64b4d17.scope - libcontainer container eb06b516045584f145b2b139db72ec5f2b2ac249d6a3c93867de0625c64b4d17. Sep 5 00:39:46.671375 systemd-resolved[1422]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 5 00:39:46.718339 containerd[1563]: time="2025-09-05T00:39:46.718168714Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:bae2120e-70bc-45e8-a11a-8523703edd06,Namespace:default,Attempt:0,} returns sandbox id \"eb06b516045584f145b2b139db72ec5f2b2ac249d6a3c93867de0625c64b4d17\"" Sep 5 00:39:46.719932 containerd[1563]: time="2025-09-05T00:39:46.719677252Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 5 00:39:46.916704 kubelet[1853]: E0905 00:39:46.916643 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:47.155295 containerd[1563]: time="2025-09-05T00:39:47.155070467Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:39:47.156991 containerd[1563]: time="2025-09-05T00:39:47.156789551Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 5 00:39:47.159808 containerd[1563]: time="2025-09-05T00:39:47.159724338Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"73307688\" in 440.012712ms" Sep 5 00:39:47.159808 containerd[1563]: time="2025-09-05T00:39:47.159770886Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\"" Sep 5 00:39:47.170103 containerd[1563]: time="2025-09-05T00:39:47.170014670Z" level=info msg="CreateContainer within sandbox \"eb06b516045584f145b2b139db72ec5f2b2ac249d6a3c93867de0625c64b4d17\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 5 00:39:47.184494 containerd[1563]: time="2025-09-05T00:39:47.184421206Z" level=info msg="Container af5cf5aced7e284353c64450106b6c462901755a0f941f054c5cb50623544f40: CDI devices from CRI Config.CDIDevices: []" Sep 5 00:39:47.188708 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3230064219.mount: Deactivated successfully. Sep 5 00:39:47.194249 containerd[1563]: time="2025-09-05T00:39:47.194191184Z" level=info msg="CreateContainer within sandbox \"eb06b516045584f145b2b139db72ec5f2b2ac249d6a3c93867de0625c64b4d17\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"af5cf5aced7e284353c64450106b6c462901755a0f941f054c5cb50623544f40\"" Sep 5 00:39:47.196180 containerd[1563]: time="2025-09-05T00:39:47.194788936Z" level=info msg="StartContainer for \"af5cf5aced7e284353c64450106b6c462901755a0f941f054c5cb50623544f40\"" Sep 5 00:39:47.196180 containerd[1563]: time="2025-09-05T00:39:47.195832631Z" level=info msg="connecting to shim af5cf5aced7e284353c64450106b6c462901755a0f941f054c5cb50623544f40" address="unix:///run/containerd/s/be6dd20a1f63401d69143c6ec4a3e3806d8af6dc765959269e5efdfeab3f69ba" protocol=ttrpc version=3 Sep 5 00:39:47.243509 systemd[1]: Started cri-containerd-af5cf5aced7e284353c64450106b6c462901755a0f941f054c5cb50623544f40.scope - libcontainer container af5cf5aced7e284353c64450106b6c462901755a0f941f054c5cb50623544f40. Sep 5 00:39:47.286579 containerd[1563]: time="2025-09-05T00:39:47.286527635Z" level=info msg="StartContainer for \"af5cf5aced7e284353c64450106b6c462901755a0f941f054c5cb50623544f40\" returns successfully" Sep 5 00:39:47.917006 kubelet[1853]: E0905 00:39:47.916912 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:48.123491 systemd-networkd[1477]: vethfe75ab72: Gained IPv6LL Sep 5 00:39:48.917886 kubelet[1853]: E0905 00:39:48.917657 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:49.918920 kubelet[1853]: E0905 00:39:49.918825 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:50.919096 kubelet[1853]: E0905 00:39:50.919035 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:39:51.919601 kubelet[1853]: E0905 00:39:51.919509 1853 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"