Nov 1 00:25:03.031844 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Fri Oct 31 22:41:55 -00 2025 Nov 1 00:25:03.031863 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=hetzner verity.usrhash=ade41980c48607de3d2d18dc444731ec5388853e3a75ed2d5a13ce616b36f478 Nov 1 00:25:03.031870 kernel: BIOS-provided physical RAM map: Nov 1 00:25:03.031875 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Nov 1 00:25:03.031880 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Nov 1 00:25:03.031884 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Nov 1 00:25:03.031889 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007cfdbfff] usable Nov 1 00:25:03.031894 kernel: BIOS-e820: [mem 0x000000007cfdc000-0x000000007cffffff] reserved Nov 1 00:25:03.031900 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Nov 1 00:25:03.031905 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Nov 1 00:25:03.031909 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Nov 1 00:25:03.031914 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Nov 1 00:25:03.031918 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Nov 1 00:25:03.031923 kernel: NX (Execute Disable) protection: active Nov 1 00:25:03.031930 kernel: APIC: Static calls initialized Nov 1 00:25:03.031935 kernel: SMBIOS 3.0.0 present. Nov 1 00:25:03.031941 kernel: DMI: Hetzner vServer/Standard PC (Q35 + ICH9, 2009), BIOS 20171111 11/11/2017 Nov 1 00:25:03.031945 kernel: Hypervisor detected: KVM Nov 1 00:25:03.031950 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 1 00:25:03.031955 kernel: kvm-clock: using sched offset of 3071747812 cycles Nov 1 00:25:03.031960 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 1 00:25:03.031966 kernel: tsc: Detected 2445.406 MHz processor Nov 1 00:25:03.031971 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 1 00:25:03.031978 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 1 00:25:03.031983 kernel: last_pfn = 0x7cfdc max_arch_pfn = 0x400000000 Nov 1 00:25:03.031991 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Nov 1 00:25:03.032000 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 1 00:25:03.032009 kernel: Using GB pages for direct mapping Nov 1 00:25:03.032023 kernel: ACPI: Early table checksum verification disabled Nov 1 00:25:03.032033 kernel: ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS ) Nov 1 00:25:03.032041 kernel: ACPI: RSDT 0x000000007CFE2693 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 00:25:03.032050 kernel: ACPI: FACP 0x000000007CFE2483 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 00:25:03.032062 kernel: ACPI: DSDT 0x000000007CFE0040 002443 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 00:25:03.032071 kernel: ACPI: FACS 0x000000007CFE0000 000040 Nov 1 00:25:03.032081 kernel: ACPI: APIC 0x000000007CFE2577 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 00:25:03.032087 kernel: ACPI: HPET 0x000000007CFE25F7 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 00:25:03.032092 kernel: ACPI: MCFG 0x000000007CFE262F 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 00:25:03.032097 kernel: ACPI: WAET 0x000000007CFE266B 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 1 00:25:03.032102 kernel: ACPI: Reserving FACP table memory at [mem 0x7cfe2483-0x7cfe2576] Nov 1 00:25:03.032107 kernel: ACPI: Reserving DSDT table memory at [mem 0x7cfe0040-0x7cfe2482] Nov 1 00:25:03.032117 kernel: ACPI: Reserving FACS table memory at [mem 0x7cfe0000-0x7cfe003f] Nov 1 00:25:03.032122 kernel: ACPI: Reserving APIC table memory at [mem 0x7cfe2577-0x7cfe25f6] Nov 1 00:25:03.032127 kernel: ACPI: Reserving HPET table memory at [mem 0x7cfe25f7-0x7cfe262e] Nov 1 00:25:03.032133 kernel: ACPI: Reserving MCFG table memory at [mem 0x7cfe262f-0x7cfe266a] Nov 1 00:25:03.032138 kernel: ACPI: Reserving WAET table memory at [mem 0x7cfe266b-0x7cfe2692] Nov 1 00:25:03.032144 kernel: No NUMA configuration found Nov 1 00:25:03.032150 kernel: Faking a node at [mem 0x0000000000000000-0x000000007cfdbfff] Nov 1 00:25:03.032156 kernel: NODE_DATA(0) allocated [mem 0x7cfd6000-0x7cfdbfff] Nov 1 00:25:03.032161 kernel: Zone ranges: Nov 1 00:25:03.032167 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 1 00:25:03.032172 kernel: DMA32 [mem 0x0000000001000000-0x000000007cfdbfff] Nov 1 00:25:03.032177 kernel: Normal empty Nov 1 00:25:03.032182 kernel: Movable zone start for each node Nov 1 00:25:03.032187 kernel: Early memory node ranges Nov 1 00:25:03.032193 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Nov 1 00:25:03.032198 kernel: node 0: [mem 0x0000000000100000-0x000000007cfdbfff] Nov 1 00:25:03.032205 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007cfdbfff] Nov 1 00:25:03.032210 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 1 00:25:03.032215 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Nov 1 00:25:03.032220 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Nov 1 00:25:03.032226 kernel: ACPI: PM-Timer IO Port: 0x608 Nov 1 00:25:03.032231 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 1 00:25:03.032236 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 1 00:25:03.032241 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 1 00:25:03.032247 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 1 00:25:03.032253 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 1 00:25:03.032258 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 1 00:25:03.032263 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 1 00:25:03.032269 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 1 00:25:03.032274 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Nov 1 00:25:03.032279 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Nov 1 00:25:03.032284 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Nov 1 00:25:03.032289 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Nov 1 00:25:03.032295 kernel: Booting paravirtualized kernel on KVM Nov 1 00:25:03.032301 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 1 00:25:03.032307 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Nov 1 00:25:03.032313 kernel: percpu: Embedded 58 pages/cpu s196712 r8192 d32664 u1048576 Nov 1 00:25:03.032318 kernel: pcpu-alloc: s196712 r8192 d32664 u1048576 alloc=1*2097152 Nov 1 00:25:03.032323 kernel: pcpu-alloc: [0] 0 1 Nov 1 00:25:03.032328 kernel: kvm-guest: PV spinlocks disabled, no host support Nov 1 00:25:03.032334 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=hetzner verity.usrhash=ade41980c48607de3d2d18dc444731ec5388853e3a75ed2d5a13ce616b36f478 Nov 1 00:25:03.032340 kernel: random: crng init done Nov 1 00:25:03.032362 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 1 00:25:03.032372 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 1 00:25:03.032380 kernel: Fallback order for Node 0: 0 Nov 1 00:25:03.032389 kernel: Built 1 zonelists, mobility grouping on. Total pages: 503708 Nov 1 00:25:03.032398 kernel: Policy zone: DMA32 Nov 1 00:25:03.032407 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 1 00:25:03.032416 kernel: Memory: 1922052K/2047464K available (12288K kernel code, 2288K rwdata, 22748K rodata, 42884K init, 2316K bss, 125152K reserved, 0K cma-reserved) Nov 1 00:25:03.032422 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 1 00:25:03.032427 kernel: ftrace: allocating 37980 entries in 149 pages Nov 1 00:25:03.032435 kernel: ftrace: allocated 149 pages with 4 groups Nov 1 00:25:03.032440 kernel: Dynamic Preempt: voluntary Nov 1 00:25:03.032445 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 1 00:25:03.032451 kernel: rcu: RCU event tracing is enabled. Nov 1 00:25:03.032457 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 1 00:25:03.032462 kernel: Trampoline variant of Tasks RCU enabled. Nov 1 00:25:03.032467 kernel: Rude variant of Tasks RCU enabled. Nov 1 00:25:03.032473 kernel: Tracing variant of Tasks RCU enabled. Nov 1 00:25:03.032478 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 1 00:25:03.032483 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 1 00:25:03.032490 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Nov 1 00:25:03.032495 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 1 00:25:03.032500 kernel: Console: colour VGA+ 80x25 Nov 1 00:25:03.033100 kernel: printk: console [tty0] enabled Nov 1 00:25:03.033108 kernel: printk: console [ttyS0] enabled Nov 1 00:25:03.033113 kernel: ACPI: Core revision 20230628 Nov 1 00:25:03.033119 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Nov 1 00:25:03.033124 kernel: APIC: Switch to symmetric I/O mode setup Nov 1 00:25:03.033130 kernel: x2apic enabled Nov 1 00:25:03.033138 kernel: APIC: Switched APIC routing to: physical x2apic Nov 1 00:25:03.033144 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 1 00:25:03.033149 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Nov 1 00:25:03.033155 kernel: Calibrating delay loop (skipped) preset value.. 4890.81 BogoMIPS (lpj=2445406) Nov 1 00:25:03.033160 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Nov 1 00:25:03.033165 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Nov 1 00:25:03.033170 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Nov 1 00:25:03.033176 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 1 00:25:03.033187 kernel: Spectre V2 : Mitigation: Retpolines Nov 1 00:25:03.033193 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 1 00:25:03.033199 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Nov 1 00:25:03.033205 kernel: active return thunk: retbleed_return_thunk Nov 1 00:25:03.033212 kernel: RETBleed: Mitigation: untrained return thunk Nov 1 00:25:03.033217 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Nov 1 00:25:03.033223 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Nov 1 00:25:03.033229 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 1 00:25:03.033236 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 1 00:25:03.033241 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 1 00:25:03.033247 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 1 00:25:03.033253 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Nov 1 00:25:03.033258 kernel: Freeing SMP alternatives memory: 32K Nov 1 00:25:03.033264 kernel: pid_max: default: 32768 minimum: 301 Nov 1 00:25:03.033269 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 1 00:25:03.033275 kernel: landlock: Up and running. Nov 1 00:25:03.033281 kernel: SELinux: Initializing. Nov 1 00:25:03.033288 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Nov 1 00:25:03.033293 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Nov 1 00:25:03.033299 kernel: smpboot: CPU0: AMD EPYC-Rome Processor (family: 0x17, model: 0x31, stepping: 0x0) Nov 1 00:25:03.033305 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 1 00:25:03.033311 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 1 00:25:03.033316 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 1 00:25:03.033322 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Nov 1 00:25:03.033327 kernel: ... version: 0 Nov 1 00:25:03.033333 kernel: ... bit width: 48 Nov 1 00:25:03.033340 kernel: ... generic registers: 6 Nov 1 00:25:03.033358 kernel: ... value mask: 0000ffffffffffff Nov 1 00:25:03.033364 kernel: ... max period: 00007fffffffffff Nov 1 00:25:03.033370 kernel: ... fixed-purpose events: 0 Nov 1 00:25:03.033375 kernel: ... event mask: 000000000000003f Nov 1 00:25:03.033381 kernel: signal: max sigframe size: 1776 Nov 1 00:25:03.033386 kernel: rcu: Hierarchical SRCU implementation. Nov 1 00:25:03.033392 kernel: rcu: Max phase no-delay instances is 400. Nov 1 00:25:03.033398 kernel: smp: Bringing up secondary CPUs ... Nov 1 00:25:03.033405 kernel: smpboot: x86: Booting SMP configuration: Nov 1 00:25:03.033411 kernel: .... node #0, CPUs: #1 Nov 1 00:25:03.033416 kernel: smp: Brought up 1 node, 2 CPUs Nov 1 00:25:03.033422 kernel: smpboot: Max logical packages: 1 Nov 1 00:25:03.033427 kernel: smpboot: Total of 2 processors activated (9781.62 BogoMIPS) Nov 1 00:25:03.033433 kernel: devtmpfs: initialized Nov 1 00:25:03.033438 kernel: x86/mm: Memory block size: 128MB Nov 1 00:25:03.033444 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 1 00:25:03.033450 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 1 00:25:03.033455 kernel: pinctrl core: initialized pinctrl subsystem Nov 1 00:25:03.033462 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 1 00:25:03.033468 kernel: audit: initializing netlink subsys (disabled) Nov 1 00:25:03.033474 kernel: audit: type=2000 audit(1761956702.490:1): state=initialized audit_enabled=0 res=1 Nov 1 00:25:03.033479 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 1 00:25:03.033485 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 1 00:25:03.033490 kernel: cpuidle: using governor menu Nov 1 00:25:03.033496 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 1 00:25:03.033502 kernel: dca service started, version 1.12.1 Nov 1 00:25:03.033520 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Nov 1 00:25:03.033528 kernel: PCI: Using configuration type 1 for base access Nov 1 00:25:03.033534 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 1 00:25:03.033539 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 1 00:25:03.033545 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 1 00:25:03.033551 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 1 00:25:03.033557 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 1 00:25:03.033562 kernel: ACPI: Added _OSI(Module Device) Nov 1 00:25:03.033568 kernel: ACPI: Added _OSI(Processor Device) Nov 1 00:25:03.033573 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 1 00:25:03.033580 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 1 00:25:03.033586 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Nov 1 00:25:03.033591 kernel: ACPI: Interpreter enabled Nov 1 00:25:03.033597 kernel: ACPI: PM: (supports S0 S5) Nov 1 00:25:03.033602 kernel: ACPI: Using IOAPIC for interrupt routing Nov 1 00:25:03.033608 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 1 00:25:03.033613 kernel: PCI: Using E820 reservations for host bridge windows Nov 1 00:25:03.033619 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Nov 1 00:25:03.033625 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 1 00:25:03.033739 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 1 00:25:03.033810 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Nov 1 00:25:03.033871 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Nov 1 00:25:03.033880 kernel: PCI host bridge to bus 0000:00 Nov 1 00:25:03.033946 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Nov 1 00:25:03.034002 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Nov 1 00:25:03.034060 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Nov 1 00:25:03.034114 kernel: pci_bus 0000:00: root bus resource [mem 0x7d000000-0xafffffff window] Nov 1 00:25:03.034167 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Nov 1 00:25:03.034219 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Nov 1 00:25:03.034272 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 1 00:25:03.034415 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Nov 1 00:25:03.034499 kernel: pci 0000:00:01.0: [1af4:1050] type 00 class 0x030000 Nov 1 00:25:03.034597 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfb800000-0xfbffffff pref] Nov 1 00:25:03.034661 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfd200000-0xfd203fff 64bit pref] Nov 1 00:25:03.034722 kernel: pci 0000:00:01.0: reg 0x20: [mem 0xfea10000-0xfea10fff] Nov 1 00:25:03.034784 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfea00000-0xfea0ffff pref] Nov 1 00:25:03.034845 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Nov 1 00:25:03.034913 kernel: pci 0000:00:02.0: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.034981 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfea11000-0xfea11fff] Nov 1 00:25:03.035048 kernel: pci 0000:00:02.1: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.035110 kernel: pci 0000:00:02.1: reg 0x10: [mem 0xfea12000-0xfea12fff] Nov 1 00:25:03.035176 kernel: pci 0000:00:02.2: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.035239 kernel: pci 0000:00:02.2: reg 0x10: [mem 0xfea13000-0xfea13fff] Nov 1 00:25:03.035304 kernel: pci 0000:00:02.3: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.035384 kernel: pci 0000:00:02.3: reg 0x10: [mem 0xfea14000-0xfea14fff] Nov 1 00:25:03.035452 kernel: pci 0000:00:02.4: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.035533 kernel: pci 0000:00:02.4: reg 0x10: [mem 0xfea15000-0xfea15fff] Nov 1 00:25:03.035603 kernel: pci 0000:00:02.5: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.035664 kernel: pci 0000:00:02.5: reg 0x10: [mem 0xfea16000-0xfea16fff] Nov 1 00:25:03.035730 kernel: pci 0000:00:02.6: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.035796 kernel: pci 0000:00:02.6: reg 0x10: [mem 0xfea17000-0xfea17fff] Nov 1 00:25:03.035863 kernel: pci 0000:00:02.7: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.035923 kernel: pci 0000:00:02.7: reg 0x10: [mem 0xfea18000-0xfea18fff] Nov 1 00:25:03.035999 kernel: pci 0000:00:03.0: [1b36:000c] type 01 class 0x060400 Nov 1 00:25:03.036088 kernel: pci 0000:00:03.0: reg 0x10: [mem 0xfea19000-0xfea19fff] Nov 1 00:25:03.036159 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Nov 1 00:25:03.036220 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Nov 1 00:25:03.036290 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Nov 1 00:25:03.036367 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc040-0xc05f] Nov 1 00:25:03.036431 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfea1a000-0xfea1afff] Nov 1 00:25:03.036497 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Nov 1 00:25:03.036586 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Nov 1 00:25:03.036680 kernel: pci 0000:01:00.0: [1af4:1041] type 00 class 0x020000 Nov 1 00:25:03.036752 kernel: pci 0000:01:00.0: reg 0x14: [mem 0xfe880000-0xfe880fff] Nov 1 00:25:03.036816 kernel: pci 0000:01:00.0: reg 0x20: [mem 0xfd000000-0xfd003fff 64bit pref] Nov 1 00:25:03.036878 kernel: pci 0000:01:00.0: reg 0x30: [mem 0xfe800000-0xfe87ffff pref] Nov 1 00:25:03.036938 kernel: pci 0000:00:02.0: PCI bridge to [bus 01] Nov 1 00:25:03.036998 kernel: pci 0000:00:02.0: bridge window [mem 0xfe800000-0xfe9fffff] Nov 1 00:25:03.037057 kernel: pci 0000:00:02.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Nov 1 00:25:03.037125 kernel: pci 0000:02:00.0: [1b36:000d] type 00 class 0x0c0330 Nov 1 00:25:03.037193 kernel: pci 0000:02:00.0: reg 0x10: [mem 0xfe600000-0xfe603fff 64bit] Nov 1 00:25:03.037253 kernel: pci 0000:00:02.1: PCI bridge to [bus 02] Nov 1 00:25:03.037313 kernel: pci 0000:00:02.1: bridge window [mem 0xfe600000-0xfe7fffff] Nov 1 00:25:03.037403 kernel: pci 0000:00:02.1: bridge window [mem 0xfce00000-0xfcffffff 64bit pref] Nov 1 00:25:03.037473 kernel: pci 0000:03:00.0: [1af4:1043] type 00 class 0x078000 Nov 1 00:25:03.037554 kernel: pci 0000:03:00.0: reg 0x14: [mem 0xfe400000-0xfe400fff] Nov 1 00:25:03.037623 kernel: pci 0000:03:00.0: reg 0x20: [mem 0xfcc00000-0xfcc03fff 64bit pref] Nov 1 00:25:03.037684 kernel: pci 0000:00:02.2: PCI bridge to [bus 03] Nov 1 00:25:03.037744 kernel: pci 0000:00:02.2: bridge window [mem 0xfe400000-0xfe5fffff] Nov 1 00:25:03.037804 kernel: pci 0000:00:02.2: bridge window [mem 0xfcc00000-0xfcdfffff 64bit pref] Nov 1 00:25:03.037871 kernel: pci 0000:04:00.0: [1af4:1045] type 00 class 0x00ff00 Nov 1 00:25:03.037935 kernel: pci 0000:04:00.0: reg 0x20: [mem 0xfca00000-0xfca03fff 64bit pref] Nov 1 00:25:03.037994 kernel: pci 0000:00:02.3: PCI bridge to [bus 04] Nov 1 00:25:03.038053 kernel: pci 0000:00:02.3: bridge window [mem 0xfe200000-0xfe3fffff] Nov 1 00:25:03.038117 kernel: pci 0000:00:02.3: bridge window [mem 0xfca00000-0xfcbfffff 64bit pref] Nov 1 00:25:03.038188 kernel: pci 0000:05:00.0: [1af4:1044] type 00 class 0x00ff00 Nov 1 00:25:03.038251 kernel: pci 0000:05:00.0: reg 0x14: [mem 0xfe000000-0xfe000fff] Nov 1 00:25:03.038313 kernel: pci 0000:05:00.0: reg 0x20: [mem 0xfc800000-0xfc803fff 64bit pref] Nov 1 00:25:03.038390 kernel: pci 0000:00:02.4: PCI bridge to [bus 05] Nov 1 00:25:03.038450 kernel: pci 0000:00:02.4: bridge window [mem 0xfe000000-0xfe1fffff] Nov 1 00:25:03.038533 kernel: pci 0000:00:02.4: bridge window [mem 0xfc800000-0xfc9fffff 64bit pref] Nov 1 00:25:03.038613 kernel: pci 0000:06:00.0: [1af4:1048] type 00 class 0x010000 Nov 1 00:25:03.038749 kernel: pci 0000:06:00.0: reg 0x14: [mem 0xfde00000-0xfde00fff] Nov 1 00:25:03.038816 kernel: pci 0000:06:00.0: reg 0x20: [mem 0xfc600000-0xfc603fff 64bit pref] Nov 1 00:25:03.038877 kernel: pci 0000:00:02.5: PCI bridge to [bus 06] Nov 1 00:25:03.038938 kernel: pci 0000:00:02.5: bridge window [mem 0xfde00000-0xfdffffff] Nov 1 00:25:03.038997 kernel: pci 0000:00:02.5: bridge window [mem 0xfc600000-0xfc7fffff 64bit pref] Nov 1 00:25:03.039005 kernel: acpiphp: Slot [0] registered Nov 1 00:25:03.039072 kernel: pci 0000:07:00.0: [1af4:1041] type 00 class 0x020000 Nov 1 00:25:03.039141 kernel: pci 0000:07:00.0: reg 0x14: [mem 0xfdc80000-0xfdc80fff] Nov 1 00:25:03.039203 kernel: pci 0000:07:00.0: reg 0x20: [mem 0xfc400000-0xfc403fff 64bit pref] Nov 1 00:25:03.039266 kernel: pci 0000:07:00.0: reg 0x30: [mem 0xfdc00000-0xfdc7ffff pref] Nov 1 00:25:03.039326 kernel: pci 0000:00:02.6: PCI bridge to [bus 07] Nov 1 00:25:03.039403 kernel: pci 0000:00:02.6: bridge window [mem 0xfdc00000-0xfddfffff] Nov 1 00:25:03.039463 kernel: pci 0000:00:02.6: bridge window [mem 0xfc400000-0xfc5fffff 64bit pref] Nov 1 00:25:03.039471 kernel: acpiphp: Slot [0-2] registered Nov 1 00:25:03.039576 kernel: pci 0000:00:02.7: PCI bridge to [bus 08] Nov 1 00:25:03.039638 kernel: pci 0000:00:02.7: bridge window [mem 0xfda00000-0xfdbfffff] Nov 1 00:25:03.039697 kernel: pci 0000:00:02.7: bridge window [mem 0xfc200000-0xfc3fffff 64bit pref] Nov 1 00:25:03.039705 kernel: acpiphp: Slot [0-3] registered Nov 1 00:25:03.039762 kernel: pci 0000:00:03.0: PCI bridge to [bus 09] Nov 1 00:25:03.039821 kernel: pci 0000:00:03.0: bridge window [mem 0xfd800000-0xfd9fffff] Nov 1 00:25:03.039879 kernel: pci 0000:00:03.0: bridge window [mem 0xfc000000-0xfc1fffff 64bit pref] Nov 1 00:25:03.039887 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Nov 1 00:25:03.039893 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Nov 1 00:25:03.039902 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Nov 1 00:25:03.039907 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Nov 1 00:25:03.039913 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Nov 1 00:25:03.039919 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Nov 1 00:25:03.039925 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Nov 1 00:25:03.039930 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Nov 1 00:25:03.039936 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Nov 1 00:25:03.039941 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Nov 1 00:25:03.039947 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Nov 1 00:25:03.039954 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Nov 1 00:25:03.039960 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Nov 1 00:25:03.039965 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Nov 1 00:25:03.039971 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Nov 1 00:25:03.039977 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Nov 1 00:25:03.039982 kernel: iommu: Default domain type: Translated Nov 1 00:25:03.039988 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 1 00:25:03.039994 kernel: PCI: Using ACPI for IRQ routing Nov 1 00:25:03.039999 kernel: PCI: pci_cache_line_size set to 64 bytes Nov 1 00:25:03.040006 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Nov 1 00:25:03.040012 kernel: e820: reserve RAM buffer [mem 0x7cfdc000-0x7fffffff] Nov 1 00:25:03.040070 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Nov 1 00:25:03.040130 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Nov 1 00:25:03.040190 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Nov 1 00:25:03.040198 kernel: vgaarb: loaded Nov 1 00:25:03.040204 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Nov 1 00:25:03.040210 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Nov 1 00:25:03.040216 kernel: clocksource: Switched to clocksource kvm-clock Nov 1 00:25:03.040224 kernel: VFS: Disk quotas dquot_6.6.0 Nov 1 00:25:03.040230 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 1 00:25:03.040235 kernel: pnp: PnP ACPI init Nov 1 00:25:03.040301 kernel: system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved Nov 1 00:25:03.040310 kernel: pnp: PnP ACPI: found 5 devices Nov 1 00:25:03.040316 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 1 00:25:03.040322 kernel: NET: Registered PF_INET protocol family Nov 1 00:25:03.040327 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 1 00:25:03.040336 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Nov 1 00:25:03.040341 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 1 00:25:03.040359 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 1 00:25:03.040365 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Nov 1 00:25:03.040371 kernel: TCP: Hash tables configured (established 16384 bind 16384) Nov 1 00:25:03.040377 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Nov 1 00:25:03.040382 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Nov 1 00:25:03.040388 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 1 00:25:03.040394 kernel: NET: Registered PF_XDP protocol family Nov 1 00:25:03.040461 kernel: pci 0000:00:02.6: bridge window [io 0x1000-0x0fff] to [bus 07] add_size 1000 Nov 1 00:25:03.040631 kernel: pci 0000:00:02.7: bridge window [io 0x1000-0x0fff] to [bus 08] add_size 1000 Nov 1 00:25:03.040739 kernel: pci 0000:00:03.0: bridge window [io 0x1000-0x0fff] to [bus 09] add_size 1000 Nov 1 00:25:03.040832 kernel: pci 0000:00:02.6: BAR 13: assigned [io 0x1000-0x1fff] Nov 1 00:25:03.040895 kernel: pci 0000:00:02.7: BAR 13: assigned [io 0x2000-0x2fff] Nov 1 00:25:03.040954 kernel: pci 0000:00:03.0: BAR 13: assigned [io 0x3000-0x3fff] Nov 1 00:25:03.041012 kernel: pci 0000:00:02.0: PCI bridge to [bus 01] Nov 1 00:25:03.041076 kernel: pci 0000:00:02.0: bridge window [mem 0xfe800000-0xfe9fffff] Nov 1 00:25:03.041135 kernel: pci 0000:00:02.0: bridge window [mem 0xfd000000-0xfd1fffff 64bit pref] Nov 1 00:25:03.041194 kernel: pci 0000:00:02.1: PCI bridge to [bus 02] Nov 1 00:25:03.041253 kernel: pci 0000:00:02.1: bridge window [mem 0xfe600000-0xfe7fffff] Nov 1 00:25:03.041311 kernel: pci 0000:00:02.1: bridge window [mem 0xfce00000-0xfcffffff 64bit pref] Nov 1 00:25:03.041386 kernel: pci 0000:00:02.2: PCI bridge to [bus 03] Nov 1 00:25:03.041446 kernel: pci 0000:00:02.2: bridge window [mem 0xfe400000-0xfe5fffff] Nov 1 00:25:03.041520 kernel: pci 0000:00:02.2: bridge window [mem 0xfcc00000-0xfcdfffff 64bit pref] Nov 1 00:25:03.041662 kernel: pci 0000:00:02.3: PCI bridge to [bus 04] Nov 1 00:25:03.041756 kernel: pci 0000:00:02.3: bridge window [mem 0xfe200000-0xfe3fffff] Nov 1 00:25:03.041820 kernel: pci 0000:00:02.3: bridge window [mem 0xfca00000-0xfcbfffff 64bit pref] Nov 1 00:25:03.042006 kernel: pci 0000:00:02.4: PCI bridge to [bus 05] Nov 1 00:25:03.042176 kernel: pci 0000:00:02.4: bridge window [mem 0xfe000000-0xfe1fffff] Nov 1 00:25:03.042291 kernel: pci 0000:00:02.4: bridge window [mem 0xfc800000-0xfc9fffff 64bit pref] Nov 1 00:25:03.042439 kernel: pci 0000:00:02.5: PCI bridge to [bus 06] Nov 1 00:25:03.044675 kernel: pci 0000:00:02.5: bridge window [mem 0xfde00000-0xfdffffff] Nov 1 00:25:03.044807 kernel: pci 0000:00:02.5: bridge window [mem 0xfc600000-0xfc7fffff 64bit pref] Nov 1 00:25:03.044915 kernel: pci 0000:00:02.6: PCI bridge to [bus 07] Nov 1 00:25:03.045019 kernel: pci 0000:00:02.6: bridge window [io 0x1000-0x1fff] Nov 1 00:25:03.045120 kernel: pci 0000:00:02.6: bridge window [mem 0xfdc00000-0xfddfffff] Nov 1 00:25:03.045220 kernel: pci 0000:00:02.6: bridge window [mem 0xfc400000-0xfc5fffff 64bit pref] Nov 1 00:25:03.045324 kernel: pci 0000:00:02.7: PCI bridge to [bus 08] Nov 1 00:25:03.045451 kernel: pci 0000:00:02.7: bridge window [io 0x2000-0x2fff] Nov 1 00:25:03.045670 kernel: pci 0000:00:02.7: bridge window [mem 0xfda00000-0xfdbfffff] Nov 1 00:25:03.045788 kernel: pci 0000:00:02.7: bridge window [mem 0xfc200000-0xfc3fffff 64bit pref] Nov 1 00:25:03.045904 kernel: pci 0000:00:03.0: PCI bridge to [bus 09] Nov 1 00:25:03.046005 kernel: pci 0000:00:03.0: bridge window [io 0x3000-0x3fff] Nov 1 00:25:03.046104 kernel: pci 0000:00:03.0: bridge window [mem 0xfd800000-0xfd9fffff] Nov 1 00:25:03.046213 kernel: pci 0000:00:03.0: bridge window [mem 0xfc000000-0xfc1fffff 64bit pref] Nov 1 00:25:03.046314 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Nov 1 00:25:03.047819 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Nov 1 00:25:03.047928 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Nov 1 00:25:03.048019 kernel: pci_bus 0000:00: resource 7 [mem 0x7d000000-0xafffffff window] Nov 1 00:25:03.048106 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Nov 1 00:25:03.048194 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Nov 1 00:25:03.048307 kernel: pci_bus 0000:01: resource 1 [mem 0xfe800000-0xfe9fffff] Nov 1 00:25:03.048435 kernel: pci_bus 0000:01: resource 2 [mem 0xfd000000-0xfd1fffff 64bit pref] Nov 1 00:25:03.049656 kernel: pci_bus 0000:02: resource 1 [mem 0xfe600000-0xfe7fffff] Nov 1 00:25:03.049766 kernel: pci_bus 0000:02: resource 2 [mem 0xfce00000-0xfcffffff 64bit pref] Nov 1 00:25:03.049868 kernel: pci_bus 0000:03: resource 1 [mem 0xfe400000-0xfe5fffff] Nov 1 00:25:03.049960 kernel: pci_bus 0000:03: resource 2 [mem 0xfcc00000-0xfcdfffff 64bit pref] Nov 1 00:25:03.050069 kernel: pci_bus 0000:04: resource 1 [mem 0xfe200000-0xfe3fffff] Nov 1 00:25:03.050165 kernel: pci_bus 0000:04: resource 2 [mem 0xfca00000-0xfcbfffff 64bit pref] Nov 1 00:25:03.050265 kernel: pci_bus 0000:05: resource 1 [mem 0xfe000000-0xfe1fffff] Nov 1 00:25:03.050383 kernel: pci_bus 0000:05: resource 2 [mem 0xfc800000-0xfc9fffff 64bit pref] Nov 1 00:25:03.050494 kernel: pci_bus 0000:06: resource 1 [mem 0xfde00000-0xfdffffff] Nov 1 00:25:03.051676 kernel: pci_bus 0000:06: resource 2 [mem 0xfc600000-0xfc7fffff 64bit pref] Nov 1 00:25:03.051791 kernel: pci_bus 0000:07: resource 0 [io 0x1000-0x1fff] Nov 1 00:25:03.051884 kernel: pci_bus 0000:07: resource 1 [mem 0xfdc00000-0xfddfffff] Nov 1 00:25:03.051974 kernel: pci_bus 0000:07: resource 2 [mem 0xfc400000-0xfc5fffff 64bit pref] Nov 1 00:25:03.052073 kernel: pci_bus 0000:08: resource 0 [io 0x2000-0x2fff] Nov 1 00:25:03.052166 kernel: pci_bus 0000:08: resource 1 [mem 0xfda00000-0xfdbfffff] Nov 1 00:25:03.052256 kernel: pci_bus 0000:08: resource 2 [mem 0xfc200000-0xfc3fffff 64bit pref] Nov 1 00:25:03.052409 kernel: pci_bus 0000:09: resource 0 [io 0x3000-0x3fff] Nov 1 00:25:03.053568 kernel: pci_bus 0000:09: resource 1 [mem 0xfd800000-0xfd9fffff] Nov 1 00:25:03.053684 kernel: pci_bus 0000:09: resource 2 [mem 0xfc000000-0xfc1fffff 64bit pref] Nov 1 00:25:03.053701 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Nov 1 00:25:03.053712 kernel: PCI: CLS 0 bytes, default 64 Nov 1 00:25:03.053723 kernel: Initialise system trusted keyrings Nov 1 00:25:03.053734 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Nov 1 00:25:03.053744 kernel: Key type asymmetric registered Nov 1 00:25:03.053754 kernel: Asymmetric key parser 'x509' registered Nov 1 00:25:03.053769 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 1 00:25:03.053778 kernel: io scheduler mq-deadline registered Nov 1 00:25:03.053788 kernel: io scheduler kyber registered Nov 1 00:25:03.053798 kernel: io scheduler bfq registered Nov 1 00:25:03.053907 kernel: pcieport 0000:00:02.0: PME: Signaling with IRQ 24 Nov 1 00:25:03.054014 kernel: pcieport 0000:00:02.0: AER: enabled with IRQ 24 Nov 1 00:25:03.054115 kernel: pcieport 0000:00:02.1: PME: Signaling with IRQ 25 Nov 1 00:25:03.054217 kernel: pcieport 0000:00:02.1: AER: enabled with IRQ 25 Nov 1 00:25:03.054321 kernel: pcieport 0000:00:02.2: PME: Signaling with IRQ 26 Nov 1 00:25:03.054455 kernel: pcieport 0000:00:02.2: AER: enabled with IRQ 26 Nov 1 00:25:03.055579 kernel: pcieport 0000:00:02.3: PME: Signaling with IRQ 27 Nov 1 00:25:03.055649 kernel: pcieport 0000:00:02.3: AER: enabled with IRQ 27 Nov 1 00:25:03.055711 kernel: pcieport 0000:00:02.4: PME: Signaling with IRQ 28 Nov 1 00:25:03.055772 kernel: pcieport 0000:00:02.4: AER: enabled with IRQ 28 Nov 1 00:25:03.055833 kernel: pcieport 0000:00:02.5: PME: Signaling with IRQ 29 Nov 1 00:25:03.055894 kernel: pcieport 0000:00:02.5: AER: enabled with IRQ 29 Nov 1 00:25:03.055956 kernel: pcieport 0000:00:02.6: PME: Signaling with IRQ 30 Nov 1 00:25:03.056022 kernel: pcieport 0000:00:02.6: AER: enabled with IRQ 30 Nov 1 00:25:03.056083 kernel: pcieport 0000:00:02.7: PME: Signaling with IRQ 31 Nov 1 00:25:03.056143 kernel: pcieport 0000:00:02.7: AER: enabled with IRQ 31 Nov 1 00:25:03.056153 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Nov 1 00:25:03.056212 kernel: pcieport 0000:00:03.0: PME: Signaling with IRQ 32 Nov 1 00:25:03.056273 kernel: pcieport 0000:00:03.0: AER: enabled with IRQ 32 Nov 1 00:25:03.056282 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 1 00:25:03.056289 kernel: ACPI: \_SB_.GSIF: Enabled at IRQ 21 Nov 1 00:25:03.056298 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 1 00:25:03.056304 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 1 00:25:03.056311 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 1 00:25:03.056317 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 1 00:25:03.056323 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 1 00:25:03.056406 kernel: rtc_cmos 00:03: RTC can wake from S4 Nov 1 00:25:03.056416 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Nov 1 00:25:03.056474 kernel: rtc_cmos 00:03: registered as rtc0 Nov 1 00:25:03.057577 kernel: rtc_cmos 00:03: setting system clock to 2025-11-01T00:25:02 UTC (1761956702) Nov 1 00:25:03.057639 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Nov 1 00:25:03.057649 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Nov 1 00:25:03.057656 kernel: NET: Registered PF_INET6 protocol family Nov 1 00:25:03.057662 kernel: Segment Routing with IPv6 Nov 1 00:25:03.057669 kernel: In-situ OAM (IOAM) with IPv6 Nov 1 00:25:03.057675 kernel: NET: Registered PF_PACKET protocol family Nov 1 00:25:03.057681 kernel: Key type dns_resolver registered Nov 1 00:25:03.057687 kernel: IPI shorthand broadcast: enabled Nov 1 00:25:03.057697 kernel: sched_clock: Marking stable (1168010787, 145442686)->(1322567248, -9113775) Nov 1 00:25:03.057703 kernel: registered taskstats version 1 Nov 1 00:25:03.057709 kernel: Loading compiled-in X.509 certificates Nov 1 00:25:03.057715 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: cc4975b6f5d9e3149f7a95c8552b8f9120c3a1f4' Nov 1 00:25:03.057721 kernel: Key type .fscrypt registered Nov 1 00:25:03.057727 kernel: Key type fscrypt-provisioning registered Nov 1 00:25:03.057733 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 1 00:25:03.057739 kernel: ima: Allocated hash algorithm: sha1 Nov 1 00:25:03.057745 kernel: ima: No architecture policies found Nov 1 00:25:03.057753 kernel: clk: Disabling unused clocks Nov 1 00:25:03.057759 kernel: Freeing unused kernel image (initmem) memory: 42884K Nov 1 00:25:03.057765 kernel: Write protecting the kernel read-only data: 36864k Nov 1 00:25:03.057772 kernel: Freeing unused kernel image (rodata/data gap) memory: 1828K Nov 1 00:25:03.057778 kernel: Run /init as init process Nov 1 00:25:03.057784 kernel: with arguments: Nov 1 00:25:03.057790 kernel: /init Nov 1 00:25:03.057796 kernel: with environment: Nov 1 00:25:03.057802 kernel: HOME=/ Nov 1 00:25:03.057808 kernel: TERM=linux Nov 1 00:25:03.057817 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 1 00:25:03.057826 systemd[1]: Detected virtualization kvm. Nov 1 00:25:03.057833 systemd[1]: Detected architecture x86-64. Nov 1 00:25:03.057839 systemd[1]: Running in initrd. Nov 1 00:25:03.057846 systemd[1]: No hostname configured, using default hostname. Nov 1 00:25:03.057852 systemd[1]: Hostname set to . Nov 1 00:25:03.057859 systemd[1]: Initializing machine ID from VM UUID. Nov 1 00:25:03.057867 systemd[1]: Queued start job for default target initrd.target. Nov 1 00:25:03.057874 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 1 00:25:03.057881 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 1 00:25:03.057888 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 1 00:25:03.057895 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 1 00:25:03.057902 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 1 00:25:03.057908 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 1 00:25:03.057918 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 1 00:25:03.057925 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 1 00:25:03.057931 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 1 00:25:03.057938 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 1 00:25:03.057944 systemd[1]: Reached target paths.target - Path Units. Nov 1 00:25:03.057951 systemd[1]: Reached target slices.target - Slice Units. Nov 1 00:25:03.057958 systemd[1]: Reached target swap.target - Swaps. Nov 1 00:25:03.057964 systemd[1]: Reached target timers.target - Timer Units. Nov 1 00:25:03.057972 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 1 00:25:03.057978 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 1 00:25:03.057985 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 1 00:25:03.057991 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Nov 1 00:25:03.057998 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 1 00:25:03.058004 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 1 00:25:03.058011 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 1 00:25:03.058017 systemd[1]: Reached target sockets.target - Socket Units. Nov 1 00:25:03.058025 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 1 00:25:03.058031 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 1 00:25:03.058038 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 1 00:25:03.058044 systemd[1]: Starting systemd-fsck-usr.service... Nov 1 00:25:03.058051 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 1 00:25:03.058057 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 1 00:25:03.058064 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 00:25:03.058070 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 1 00:25:03.058076 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 1 00:25:03.058084 systemd[1]: Finished systemd-fsck-usr.service. Nov 1 00:25:03.058091 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 1 00:25:03.058098 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 1 00:25:03.058105 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 1 00:25:03.058129 systemd-journald[186]: Collecting audit messages is disabled. Nov 1 00:25:03.058147 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 1 00:25:03.058155 systemd-journald[186]: Journal started Nov 1 00:25:03.058173 systemd-journald[186]: Runtime Journal (/run/log/journal/30c52de4ab904d3c9c367688b90485bc) is 4.8M, max 38.4M, 33.6M free. Nov 1 00:25:03.042627 systemd-modules-load[187]: Inserted module 'overlay' Nov 1 00:25:03.086212 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 1 00:25:03.086237 kernel: Bridge firewalling registered Nov 1 00:25:03.086246 systemd[1]: Started systemd-journald.service - Journal Service. Nov 1 00:25:03.073551 systemd-modules-load[187]: Inserted module 'br_netfilter' Nov 1 00:25:03.087336 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 1 00:25:03.089920 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 00:25:03.100672 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 1 00:25:03.102791 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 1 00:25:03.106704 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 1 00:25:03.116234 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 1 00:25:03.122681 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 1 00:25:03.123205 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 1 00:25:03.127413 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 1 00:25:03.134659 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 1 00:25:03.138528 dracut-cmdline[217]: dracut-dracut-053 Nov 1 00:25:03.142528 dracut-cmdline[217]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=hetzner verity.usrhash=ade41980c48607de3d2d18dc444731ec5388853e3a75ed2d5a13ce616b36f478 Nov 1 00:25:03.170673 systemd-resolved[224]: Positive Trust Anchors: Nov 1 00:25:03.170691 systemd-resolved[224]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 1 00:25:03.170730 systemd-resolved[224]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 1 00:25:03.175298 systemd-resolved[224]: Defaulting to hostname 'linux'. Nov 1 00:25:03.176417 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 1 00:25:03.178291 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 1 00:25:03.209553 kernel: SCSI subsystem initialized Nov 1 00:25:03.220549 kernel: Loading iSCSI transport class v2.0-870. Nov 1 00:25:03.228547 kernel: iscsi: registered transport (tcp) Nov 1 00:25:03.245554 kernel: iscsi: registered transport (qla4xxx) Nov 1 00:25:03.245597 kernel: QLogic iSCSI HBA Driver Nov 1 00:25:03.286816 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 1 00:25:03.295635 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 1 00:25:03.331668 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 1 00:25:03.331748 kernel: device-mapper: uevent: version 1.0.3 Nov 1 00:25:03.331770 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 1 00:25:03.383588 kernel: raid6: avx2x4 gen() 18380 MB/s Nov 1 00:25:03.401565 kernel: raid6: avx2x2 gen() 21417 MB/s Nov 1 00:25:03.418674 kernel: raid6: avx2x1 gen() 19987 MB/s Nov 1 00:25:03.418726 kernel: raid6: using algorithm avx2x2 gen() 21417 MB/s Nov 1 00:25:03.436745 kernel: raid6: .... xor() 32563 MB/s, rmw enabled Nov 1 00:25:03.436795 kernel: raid6: using avx2x2 recovery algorithm Nov 1 00:25:03.453561 kernel: xor: automatically using best checksumming function avx Nov 1 00:25:03.574556 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 1 00:25:03.586772 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 1 00:25:03.597674 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 1 00:25:03.612165 systemd-udevd[404]: Using default interface naming scheme 'v255'. Nov 1 00:25:03.618314 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 1 00:25:03.624670 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 1 00:25:03.656919 dracut-pre-trigger[410]: rd.md=0: removing MD RAID activation Nov 1 00:25:03.701849 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 1 00:25:03.711736 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 1 00:25:03.770579 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 1 00:25:03.777647 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 1 00:25:03.806428 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 1 00:25:03.809409 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 1 00:25:03.812235 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 1 00:25:03.814916 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 1 00:25:03.821770 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 1 00:25:03.838742 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 1 00:25:03.852565 kernel: scsi host0: Virtio SCSI HBA Nov 1 00:25:03.855557 kernel: scsi 0:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Nov 1 00:25:03.863537 kernel: cryptd: max_cpu_qlen set to 1000 Nov 1 00:25:03.875934 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 1 00:25:03.876037 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 1 00:25:03.877861 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 1 00:25:03.879156 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 1 00:25:03.879252 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 00:25:03.886202 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 00:25:03.894658 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 00:25:03.899554 kernel: AVX2 version of gcm_enc/dec engaged. Nov 1 00:25:03.902691 kernel: AES CTR mode by8 optimization enabled Nov 1 00:25:03.908534 kernel: ACPI: bus type USB registered Nov 1 00:25:03.908564 kernel: usbcore: registered new interface driver usbfs Nov 1 00:25:03.909537 kernel: usbcore: registered new interface driver hub Nov 1 00:25:03.909556 kernel: usbcore: registered new device driver usb Nov 1 00:25:03.918617 kernel: libata version 3.00 loaded. Nov 1 00:25:03.923528 kernel: ahci 0000:00:1f.2: version 3.0 Nov 1 00:25:03.923687 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Nov 1 00:25:03.925526 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Nov 1 00:25:03.925651 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Nov 1 00:25:03.925792 kernel: scsi host1: ahci Nov 1 00:25:03.976608 kernel: xhci_hcd 0000:02:00.0: xHCI Host Controller Nov 1 00:25:03.976762 kernel: xhci_hcd 0000:02:00.0: new USB bus registered, assigned bus number 1 Nov 1 00:25:03.976849 kernel: xhci_hcd 0000:02:00.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 Nov 1 00:25:03.977536 kernel: xhci_hcd 0000:02:00.0: xHCI Host Controller Nov 1 00:25:03.977642 kernel: xhci_hcd 0000:02:00.0: new USB bus registered, assigned bus number 2 Nov 1 00:25:03.977722 kernel: xhci_hcd 0000:02:00.0: Host supports USB 3.0 SuperSpeed Nov 1 00:25:03.977807 kernel: hub 1-0:1.0: USB hub found Nov 1 00:25:03.978629 kernel: hub 1-0:1.0: 4 ports detected Nov 1 00:25:03.978735 kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Nov 1 00:25:03.978826 kernel: hub 2-0:1.0: USB hub found Nov 1 00:25:03.978909 kernel: hub 2-0:1.0: 4 ports detected Nov 1 00:25:03.982647 kernel: scsi host2: ahci Nov 1 00:25:03.982750 kernel: scsi host3: ahci Nov 1 00:25:03.985901 kernel: scsi host4: ahci Nov 1 00:25:03.988842 kernel: scsi host5: ahci Nov 1 00:25:03.988945 kernel: scsi host6: ahci Nov 1 00:25:03.995583 kernel: sd 0:0:0:0: Power-on or device reset occurred Nov 1 00:25:03.995705 kernel: ata1: SATA max UDMA/133 abar m4096@0xfea1a000 port 0xfea1a100 irq 48 Nov 1 00:25:03.996290 kernel: sd 0:0:0:0: [sda] 80003072 512-byte logical blocks: (41.0 GB/38.1 GiB) Nov 1 00:25:03.996413 kernel: ata2: SATA max UDMA/133 abar m4096@0xfea1a000 port 0xfea1a180 irq 48 Nov 1 00:25:03.997576 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 1 00:25:03.997675 kernel: ata3: SATA max UDMA/133 abar m4096@0xfea1a000 port 0xfea1a200 irq 48 Nov 1 00:25:03.997684 kernel: sd 0:0:0:0: [sda] Mode Sense: 63 00 00 08 Nov 1 00:25:03.997771 kernel: ata4: SATA max UDMA/133 abar m4096@0xfea1a000 port 0xfea1a280 irq 48 Nov 1 00:25:03.999213 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Nov 1 00:25:03.999314 kernel: ata5: SATA max UDMA/133 abar m4096@0xfea1a000 port 0xfea1a300 irq 48 Nov 1 00:25:04.004537 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Nov 1 00:25:04.004606 kernel: ata6: SATA max UDMA/133 abar m4096@0xfea1a000 port 0xfea1a380 irq 48 Nov 1 00:25:04.004617 kernel: GPT:17805311 != 80003071 Nov 1 00:25:04.025527 kernel: GPT:Alternate GPT header not at the end of the disk. Nov 1 00:25:04.025556 kernel: GPT:17805311 != 80003071 Nov 1 00:25:04.025566 kernel: GPT: Use GNU Parted to correct GPT errors. Nov 1 00:25:04.025573 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 1 00:25:04.030529 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 1 00:25:04.031153 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 00:25:04.035686 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 1 00:25:04.046972 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 1 00:25:04.222537 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Nov 1 00:25:04.315522 kernel: ata4: SATA link down (SStatus 0 SControl 300) Nov 1 00:25:04.315599 kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Nov 1 00:25:04.317216 kernel: ata6: SATA link down (SStatus 0 SControl 300) Nov 1 00:25:04.317516 kernel: ata5: SATA link down (SStatus 0 SControl 300) Nov 1 00:25:04.320166 kernel: ata2: SATA link down (SStatus 0 SControl 300) Nov 1 00:25:04.320516 kernel: ata3: SATA link down (SStatus 0 SControl 300) Nov 1 00:25:04.322533 kernel: ata1.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Nov 1 00:25:04.324124 kernel: ata1.00: applying bridge limits Nov 1 00:25:04.324189 kernel: ata1.00: configured for UDMA/100 Nov 1 00:25:04.328543 kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Nov 1 00:25:04.365031 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 1 00:25:04.380307 kernel: sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Nov 1 00:25:04.380527 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 1 00:25:04.385289 kernel: BTRFS: device fsid 5d5360dd-ce7d-46d0-bc66-772f2084023b devid 1 transid 34 /dev/sda3 scanned by (udev-worker) (461) Nov 1 00:25:04.386035 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - QEMU_HARDDISK EFI-SYSTEM. Nov 1 00:25:04.390456 kernel: usbcore: registered new interface driver usbhid Nov 1 00:25:04.390474 kernel: usbhid: USB HID core driver Nov 1 00:25:04.392558 kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0 Nov 1 00:25:04.399623 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/sda6 scanned by (udev-worker) (460) Nov 1 00:25:04.403214 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - QEMU_HARDDISK ROOT. Nov 1 00:25:04.404691 kernel: input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:02.1/0000:02:00.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 Nov 1 00:25:04.410532 kernel: hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:02:00.0-1/input0 Nov 1 00:25:04.414049 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - QEMU_HARDDISK USR-A. Nov 1 00:25:04.415273 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - QEMU_HARDDISK USR-A. Nov 1 00:25:04.420597 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - QEMU_HARDDISK OEM. Nov 1 00:25:04.432651 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 1 00:25:04.437444 disk-uuid[576]: Primary Header is updated. Nov 1 00:25:04.437444 disk-uuid[576]: Secondary Entries is updated. Nov 1 00:25:04.437444 disk-uuid[576]: Secondary Header is updated. Nov 1 00:25:04.442547 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 1 00:25:04.447536 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 1 00:25:04.454527 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 1 00:25:05.455878 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 1 00:25:05.458495 disk-uuid[577]: The operation has completed successfully. Nov 1 00:25:05.527785 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 1 00:25:05.527959 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 1 00:25:05.550665 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 1 00:25:05.567663 sh[597]: Success Nov 1 00:25:05.586726 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Nov 1 00:25:05.651099 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 1 00:25:05.661568 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 1 00:25:05.664070 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 1 00:25:05.695106 kernel: BTRFS info (device dm-0): first mount of filesystem 5d5360dd-ce7d-46d0-bc66-772f2084023b Nov 1 00:25:05.695160 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 1 00:25:05.698426 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 1 00:25:05.701802 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 1 00:25:05.705977 kernel: BTRFS info (device dm-0): using free space tree Nov 1 00:25:05.719543 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 1 00:25:05.722499 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 1 00:25:05.725082 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 1 00:25:05.733757 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 1 00:25:05.736746 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 1 00:25:05.762568 kernel: BTRFS info (device sda6): first mount of filesystem 92f9034d-7d56-482a-b71a-15e476525571 Nov 1 00:25:05.762623 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 1 00:25:05.762653 kernel: BTRFS info (device sda6): using free space tree Nov 1 00:25:05.771179 kernel: BTRFS info (device sda6): enabling ssd optimizations Nov 1 00:25:05.771225 kernel: BTRFS info (device sda6): auto enabling async discard Nov 1 00:25:05.786186 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 1 00:25:05.792569 kernel: BTRFS info (device sda6): last unmount of filesystem 92f9034d-7d56-482a-b71a-15e476525571 Nov 1 00:25:05.800457 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 1 00:25:05.808797 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 1 00:25:05.891139 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 1 00:25:05.906789 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 1 00:25:05.914184 ignition[719]: Ignition 2.19.0 Nov 1 00:25:05.914204 ignition[719]: Stage: fetch-offline Nov 1 00:25:05.914263 ignition[719]: no configs at "/usr/lib/ignition/base.d" Nov 1 00:25:05.914285 ignition[719]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Nov 1 00:25:05.914472 ignition[719]: parsed url from cmdline: "" Nov 1 00:25:05.914477 ignition[719]: no config URL provided Nov 1 00:25:05.914487 ignition[719]: reading system config file "/usr/lib/ignition/user.ign" Nov 1 00:25:05.914496 ignition[719]: no config at "/usr/lib/ignition/user.ign" Nov 1 00:25:05.919573 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 1 00:25:05.914501 ignition[719]: failed to fetch config: resource requires networking Nov 1 00:25:05.914805 ignition[719]: Ignition finished successfully Nov 1 00:25:05.934032 systemd-networkd[778]: lo: Link UP Nov 1 00:25:05.934041 systemd-networkd[778]: lo: Gained carrier Nov 1 00:25:05.935675 systemd-networkd[778]: Enumeration completed Nov 1 00:25:05.935738 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 1 00:25:05.936800 systemd[1]: Reached target network.target - Network. Nov 1 00:25:05.936848 systemd-networkd[778]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:05.936851 systemd-networkd[778]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 1 00:25:05.938101 systemd-networkd[778]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:05.938105 systemd-networkd[778]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 1 00:25:05.938841 systemd-networkd[778]: eth0: Link UP Nov 1 00:25:05.938845 systemd-networkd[778]: eth0: Gained carrier Nov 1 00:25:05.938852 systemd-networkd[778]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:05.943684 systemd-networkd[778]: eth1: Link UP Nov 1 00:25:05.943687 systemd-networkd[778]: eth1: Gained carrier Nov 1 00:25:05.943693 systemd-networkd[778]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:05.945624 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 1 00:25:05.955296 ignition[785]: Ignition 2.19.0 Nov 1 00:25:05.955311 ignition[785]: Stage: fetch Nov 1 00:25:05.955484 ignition[785]: no configs at "/usr/lib/ignition/base.d" Nov 1 00:25:05.955494 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Nov 1 00:25:05.955594 ignition[785]: parsed url from cmdline: "" Nov 1 00:25:05.955597 ignition[785]: no config URL provided Nov 1 00:25:05.955602 ignition[785]: reading system config file "/usr/lib/ignition/user.ign" Nov 1 00:25:05.955609 ignition[785]: no config at "/usr/lib/ignition/user.ign" Nov 1 00:25:05.955626 ignition[785]: GET http://169.254.169.254/hetzner/v1/userdata: attempt #1 Nov 1 00:25:05.955763 ignition[785]: GET error: Get "http://169.254.169.254/hetzner/v1/userdata": dial tcp 169.254.169.254:80: connect: network is unreachable Nov 1 00:25:05.982577 systemd-networkd[778]: eth1: DHCPv4 address 10.0.0.4/32 acquired from 10.0.0.1 Nov 1 00:25:06.011574 systemd-networkd[778]: eth0: DHCPv4 address 46.62.212.147/32, gateway 172.31.1.1 acquired from 172.31.1.1 Nov 1 00:25:06.156854 ignition[785]: GET http://169.254.169.254/hetzner/v1/userdata: attempt #2 Nov 1 00:25:06.163727 ignition[785]: GET result: OK Nov 1 00:25:06.163806 ignition[785]: parsing config with SHA512: 732cd68597a856c838f02478791783c6915136fa813d64e12470fa2cbaac4831749249591acb7a1c262f0238a286f4048955ee251376124280ecf37822d6fcf6 Nov 1 00:25:06.168021 unknown[785]: fetched base config from "system" Nov 1 00:25:06.168541 ignition[785]: fetch: fetch complete Nov 1 00:25:06.168077 unknown[785]: fetched base config from "system" Nov 1 00:25:06.168550 ignition[785]: fetch: fetch passed Nov 1 00:25:06.168086 unknown[785]: fetched user config from "hetzner" Nov 1 00:25:06.168615 ignition[785]: Ignition finished successfully Nov 1 00:25:06.172710 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 1 00:25:06.180717 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 1 00:25:06.200432 ignition[792]: Ignition 2.19.0 Nov 1 00:25:06.200454 ignition[792]: Stage: kargs Nov 1 00:25:06.200763 ignition[792]: no configs at "/usr/lib/ignition/base.d" Nov 1 00:25:06.203898 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 1 00:25:06.200780 ignition[792]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Nov 1 00:25:06.201833 ignition[792]: kargs: kargs passed Nov 1 00:25:06.201897 ignition[792]: Ignition finished successfully Nov 1 00:25:06.212802 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 1 00:25:06.231838 ignition[799]: Ignition 2.19.0 Nov 1 00:25:06.231858 ignition[799]: Stage: disks Nov 1 00:25:06.232121 ignition[799]: no configs at "/usr/lib/ignition/base.d" Nov 1 00:25:06.234846 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 1 00:25:06.232138 ignition[799]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Nov 1 00:25:06.243982 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 1 00:25:06.233290 ignition[799]: disks: disks passed Nov 1 00:25:06.245407 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 1 00:25:06.233349 ignition[799]: Ignition finished successfully Nov 1 00:25:06.247404 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 1 00:25:06.249631 systemd[1]: Reached target sysinit.target - System Initialization. Nov 1 00:25:06.251843 systemd[1]: Reached target basic.target - Basic System. Nov 1 00:25:06.259785 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 1 00:25:06.280154 systemd-fsck[807]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Nov 1 00:25:06.282738 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 1 00:25:06.290655 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 1 00:25:06.389528 kernel: EXT4-fs (sda9): mounted filesystem cb9d31b8-5e00-461c-b45e-c304d1f8091c r/w with ordered data mode. Quota mode: none. Nov 1 00:25:06.390168 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 1 00:25:06.390961 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 1 00:25:06.396564 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 1 00:25:06.398177 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 1 00:25:06.400626 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 1 00:25:06.401659 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 1 00:25:06.401680 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 1 00:25:06.407403 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 1 00:25:06.416495 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/sda6 scanned by mount (815) Nov 1 00:25:06.416524 kernel: BTRFS info (device sda6): first mount of filesystem 92f9034d-7d56-482a-b71a-15e476525571 Nov 1 00:25:06.416533 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 1 00:25:06.416541 kernel: BTRFS info (device sda6): using free space tree Nov 1 00:25:06.421878 kernel: BTRFS info (device sda6): enabling ssd optimizations Nov 1 00:25:06.421913 kernel: BTRFS info (device sda6): auto enabling async discard Nov 1 00:25:06.422022 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 1 00:25:06.426776 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 1 00:25:06.466739 initrd-setup-root[842]: cut: /sysroot/etc/passwd: No such file or directory Nov 1 00:25:06.469573 coreos-metadata[817]: Nov 01 00:25:06.469 INFO Fetching http://169.254.169.254/hetzner/v1/metadata/hostname: Attempt #1 Nov 1 00:25:06.471573 coreos-metadata[817]: Nov 01 00:25:06.470 INFO Fetch successful Nov 1 00:25:06.471573 coreos-metadata[817]: Nov 01 00:25:06.470 INFO wrote hostname ci-4081-3-6-n-c50cca3c34 to /sysroot/etc/hostname Nov 1 00:25:06.473394 initrd-setup-root[849]: cut: /sysroot/etc/group: No such file or directory Nov 1 00:25:06.473031 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 1 00:25:06.477422 initrd-setup-root[857]: cut: /sysroot/etc/shadow: No such file or directory Nov 1 00:25:06.480170 initrd-setup-root[864]: cut: /sysroot/etc/gshadow: No such file or directory Nov 1 00:25:06.549727 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 1 00:25:06.556627 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 1 00:25:06.560283 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 1 00:25:06.564530 kernel: BTRFS info (device sda6): last unmount of filesystem 92f9034d-7d56-482a-b71a-15e476525571 Nov 1 00:25:06.580524 ignition[931]: INFO : Ignition 2.19.0 Nov 1 00:25:06.580524 ignition[931]: INFO : Stage: mount Nov 1 00:25:06.580524 ignition[931]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 1 00:25:06.580524 ignition[931]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Nov 1 00:25:06.584618 ignition[931]: INFO : mount: mount passed Nov 1 00:25:06.584618 ignition[931]: INFO : Ignition finished successfully Nov 1 00:25:06.584058 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 1 00:25:06.600599 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 1 00:25:06.601258 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 1 00:25:06.691940 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 1 00:25:06.697726 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 1 00:25:06.714556 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/sda6 scanned by mount (943) Nov 1 00:25:06.720478 kernel: BTRFS info (device sda6): first mount of filesystem 92f9034d-7d56-482a-b71a-15e476525571 Nov 1 00:25:06.720558 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 1 00:25:06.724903 kernel: BTRFS info (device sda6): using free space tree Nov 1 00:25:06.730974 kernel: BTRFS info (device sda6): enabling ssd optimizations Nov 1 00:25:06.731022 kernel: BTRFS info (device sda6): auto enabling async discard Nov 1 00:25:06.736281 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 1 00:25:06.764005 ignition[960]: INFO : Ignition 2.19.0 Nov 1 00:25:06.764005 ignition[960]: INFO : Stage: files Nov 1 00:25:06.766303 ignition[960]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 1 00:25:06.766303 ignition[960]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Nov 1 00:25:06.766303 ignition[960]: DEBUG : files: compiled without relabeling support, skipping Nov 1 00:25:06.770888 ignition[960]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 1 00:25:06.770888 ignition[960]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 1 00:25:06.776051 ignition[960]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 1 00:25:06.777606 ignition[960]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 1 00:25:06.777606 ignition[960]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 1 00:25:06.777102 unknown[960]: wrote ssh authorized keys file for user: core Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 1 00:25:06.781927 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-x86-64.raw: attempt #1 Nov 1 00:25:07.121224 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Nov 1 00:25:07.314923 systemd-networkd[778]: eth0: Gained IPv6LL Nov 1 00:25:07.365575 ignition[960]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-x86-64.raw" Nov 1 00:25:07.365575 ignition[960]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Nov 1 00:25:07.370262 ignition[960]: INFO : files: op(7): op(8): [started] writing systemd drop-in "00-custom-metadata.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/00-custom-metadata.conf" Nov 1 00:25:07.370262 ignition[960]: INFO : files: op(7): op(8): [finished] writing systemd drop-in "00-custom-metadata.conf" at "/sysroot/etc/systemd/system/coreos-metadata.service.d/00-custom-metadata.conf" Nov 1 00:25:07.370262 ignition[960]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Nov 1 00:25:07.370262 ignition[960]: INFO : files: createResultFile: createFiles: op(9): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 1 00:25:07.370262 ignition[960]: INFO : files: createResultFile: createFiles: op(9): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 1 00:25:07.370262 ignition[960]: INFO : files: files passed Nov 1 00:25:07.370262 ignition[960]: INFO : Ignition finished successfully Nov 1 00:25:07.370687 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 1 00:25:07.380650 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 1 00:25:07.393695 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 1 00:25:07.394781 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 1 00:25:07.394876 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 1 00:25:07.406864 initrd-setup-root-after-ignition[988]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 1 00:25:07.406864 initrd-setup-root-after-ignition[988]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 1 00:25:07.410332 initrd-setup-root-after-ignition[992]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 1 00:25:07.410755 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 1 00:25:07.413179 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 1 00:25:07.420778 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 1 00:25:07.447933 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 1 00:25:07.448060 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 1 00:25:07.450331 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 1 00:25:07.452102 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 1 00:25:07.454036 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 1 00:25:07.463751 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 1 00:25:07.477643 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 1 00:25:07.482728 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 1 00:25:07.497047 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 1 00:25:07.498316 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 1 00:25:07.500293 systemd[1]: Stopped target timers.target - Timer Units. Nov 1 00:25:07.502422 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 1 00:25:07.502827 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 1 00:25:07.505053 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 1 00:25:07.506569 systemd[1]: Stopped target basic.target - Basic System. Nov 1 00:25:07.508734 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 1 00:25:07.510893 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 1 00:25:07.513053 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 1 00:25:07.515270 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 1 00:25:07.517536 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 1 00:25:07.520595 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 1 00:25:07.522709 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 1 00:25:07.524943 systemd[1]: Stopped target swap.target - Swaps. Nov 1 00:25:07.527081 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 1 00:25:07.527281 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 1 00:25:07.529705 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 1 00:25:07.531185 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 1 00:25:07.533242 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 1 00:25:07.533447 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 1 00:25:07.536034 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 1 00:25:07.536207 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 1 00:25:07.539156 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 1 00:25:07.539342 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 1 00:25:07.540836 systemd[1]: ignition-files.service: Deactivated successfully. Nov 1 00:25:07.540998 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 1 00:25:07.542943 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 1 00:25:07.543227 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 1 00:25:07.553039 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 1 00:25:07.555131 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 1 00:25:07.555793 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 1 00:25:07.572017 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 1 00:25:07.574797 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 1 00:25:07.575099 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 1 00:25:07.580066 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 1 00:25:07.580332 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 1 00:25:07.590110 ignition[1012]: INFO : Ignition 2.19.0 Nov 1 00:25:07.590110 ignition[1012]: INFO : Stage: umount Nov 1 00:25:07.590110 ignition[1012]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 1 00:25:07.590110 ignition[1012]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Nov 1 00:25:07.590110 ignition[1012]: INFO : umount: umount passed Nov 1 00:25:07.590110 ignition[1012]: INFO : Ignition finished successfully Nov 1 00:25:07.591771 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 1 00:25:07.591921 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 1 00:25:07.595248 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 1 00:25:07.595873 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 1 00:25:07.601964 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 1 00:25:07.602020 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 1 00:25:07.603046 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 1 00:25:07.603098 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 1 00:25:07.605722 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 1 00:25:07.605778 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 1 00:25:07.611256 systemd[1]: Stopped target network.target - Network. Nov 1 00:25:07.612750 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 1 00:25:07.612798 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 1 00:25:07.614790 systemd[1]: Stopped target paths.target - Path Units. Nov 1 00:25:07.621474 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 1 00:25:07.622433 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 1 00:25:07.623228 systemd[1]: Stopped target slices.target - Slice Units. Nov 1 00:25:07.625579 systemd[1]: Stopped target sockets.target - Socket Units. Nov 1 00:25:07.627221 systemd[1]: iscsid.socket: Deactivated successfully. Nov 1 00:25:07.627300 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 1 00:25:07.635219 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 1 00:25:07.635291 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 1 00:25:07.636308 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 1 00:25:07.636426 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 1 00:25:07.637657 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 1 00:25:07.637758 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 1 00:25:07.640160 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 1 00:25:07.642429 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 1 00:25:07.643609 systemd-networkd[778]: eth1: DHCPv6 lease lost Nov 1 00:25:07.645953 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 1 00:25:07.647390 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 1 00:25:07.647649 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 1 00:25:07.653627 systemd-networkd[778]: eth0: DHCPv6 lease lost Nov 1 00:25:07.657093 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 1 00:25:07.657265 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 1 00:25:07.660207 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 1 00:25:07.660394 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 1 00:25:07.665587 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 1 00:25:07.665664 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 1 00:25:07.669229 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 1 00:25:07.669432 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 1 00:25:07.677692 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 1 00:25:07.681548 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 1 00:25:07.681692 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 1 00:25:07.683919 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 1 00:25:07.684005 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 1 00:25:07.686075 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 1 00:25:07.686178 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 1 00:25:07.688334 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 1 00:25:07.688426 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 1 00:25:07.690831 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 1 00:25:07.716354 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 1 00:25:07.717324 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 1 00:25:07.719546 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 1 00:25:07.719683 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 1 00:25:07.722985 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 1 00:25:07.723064 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 1 00:25:07.725132 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 1 00:25:07.725188 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 1 00:25:07.727484 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 1 00:25:07.727599 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 1 00:25:07.730987 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 1 00:25:07.731056 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 1 00:25:07.733351 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 1 00:25:07.733436 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 1 00:25:07.742737 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 1 00:25:07.745582 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 1 00:25:07.745663 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 1 00:25:07.746772 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 1 00:25:07.746839 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 00:25:07.752321 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 1 00:25:07.752493 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 1 00:25:07.755346 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 1 00:25:07.764739 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 1 00:25:07.774772 systemd[1]: Switching root. Nov 1 00:25:07.821850 systemd-journald[186]: Journal stopped Nov 1 00:25:08.697627 systemd-journald[186]: Received SIGTERM from PID 1 (systemd). Nov 1 00:25:08.697681 kernel: SELinux: policy capability network_peer_controls=1 Nov 1 00:25:08.697693 kernel: SELinux: policy capability open_perms=1 Nov 1 00:25:08.697700 kernel: SELinux: policy capability extended_socket_class=1 Nov 1 00:25:08.697710 kernel: SELinux: policy capability always_check_network=0 Nov 1 00:25:08.697718 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 1 00:25:08.697727 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 1 00:25:08.697736 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 1 00:25:08.697746 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 1 00:25:08.697753 kernel: audit: type=1403 audit(1761956707.974:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 1 00:25:08.697762 systemd[1]: Successfully loaded SELinux policy in 50.406ms. Nov 1 00:25:08.697774 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 15.249ms. Nov 1 00:25:08.697784 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Nov 1 00:25:08.697792 systemd[1]: Detected virtualization kvm. Nov 1 00:25:08.697800 systemd[1]: Detected architecture x86-64. Nov 1 00:25:08.697809 systemd[1]: Detected first boot. Nov 1 00:25:08.697819 systemd[1]: Hostname set to . Nov 1 00:25:08.697827 systemd[1]: Initializing machine ID from VM UUID. Nov 1 00:25:08.697835 zram_generator::config[1054]: No configuration found. Nov 1 00:25:08.697844 systemd[1]: Populated /etc with preset unit settings. Nov 1 00:25:08.697852 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 1 00:25:08.697860 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 1 00:25:08.697868 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 1 00:25:08.697877 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 1 00:25:08.697886 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 1 00:25:08.697894 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 1 00:25:08.697902 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 1 00:25:08.697910 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 1 00:25:08.697918 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 1 00:25:08.697926 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 1 00:25:08.697934 systemd[1]: Created slice user.slice - User and Session Slice. Nov 1 00:25:08.697942 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 1 00:25:08.697950 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 1 00:25:08.697959 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 1 00:25:08.697968 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 1 00:25:08.697976 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 1 00:25:08.697984 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 1 00:25:08.697992 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 1 00:25:08.698000 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 1 00:25:08.698008 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 1 00:25:08.698017 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 1 00:25:08.698025 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 1 00:25:08.698033 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 1 00:25:08.698041 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 1 00:25:08.698050 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 1 00:25:08.698058 systemd[1]: Reached target slices.target - Slice Units. Nov 1 00:25:08.698066 systemd[1]: Reached target swap.target - Swaps. Nov 1 00:25:08.698074 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 1 00:25:08.698083 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 1 00:25:08.698091 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 1 00:25:08.698099 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 1 00:25:08.698107 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 1 00:25:08.698115 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 1 00:25:08.698122 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 1 00:25:08.698130 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 1 00:25:08.698138 systemd[1]: Mounting media.mount - External Media Directory... Nov 1 00:25:08.698146 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 00:25:08.698155 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 1 00:25:08.698163 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 1 00:25:08.698171 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 1 00:25:08.698179 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 1 00:25:08.698188 systemd[1]: Reached target machines.target - Containers. Nov 1 00:25:08.698195 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 1 00:25:08.698204 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 1 00:25:08.698212 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 1 00:25:08.698220 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 1 00:25:08.698229 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 1 00:25:08.698237 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 1 00:25:08.698245 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 1 00:25:08.698252 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 1 00:25:08.698260 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 1 00:25:08.698269 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 1 00:25:08.698277 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 1 00:25:08.698285 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 1 00:25:08.698304 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 1 00:25:08.698312 systemd[1]: Stopped systemd-fsck-usr.service. Nov 1 00:25:08.698321 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 1 00:25:08.698331 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 1 00:25:08.698342 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 1 00:25:08.698350 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 1 00:25:08.698360 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 1 00:25:08.698368 kernel: loop: module loaded Nov 1 00:25:08.698389 systemd[1]: verity-setup.service: Deactivated successfully. Nov 1 00:25:08.698397 systemd[1]: Stopped verity-setup.service. Nov 1 00:25:08.698406 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 00:25:08.698414 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 1 00:25:08.698423 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 1 00:25:08.698431 systemd[1]: Mounted media.mount - External Media Directory. Nov 1 00:25:08.698439 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 1 00:25:08.698447 kernel: fuse: init (API version 7.39) Nov 1 00:25:08.698454 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 1 00:25:08.698462 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 1 00:25:08.698470 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 1 00:25:08.698479 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 1 00:25:08.698487 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 1 00:25:08.698495 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 1 00:25:08.698532 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 1 00:25:08.698544 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 1 00:25:08.698552 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 1 00:25:08.698564 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 1 00:25:08.698590 systemd-journald[1127]: Collecting audit messages is disabled. Nov 1 00:25:08.698607 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 1 00:25:08.698616 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 1 00:25:08.698624 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 1 00:25:08.698632 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 1 00:25:08.698640 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 1 00:25:08.698650 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 1 00:25:08.698658 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 1 00:25:08.698667 systemd-journald[1127]: Journal started Nov 1 00:25:08.698683 systemd-journald[1127]: Runtime Journal (/run/log/journal/30c52de4ab904d3c9c367688b90485bc) is 4.8M, max 38.4M, 33.6M free. Nov 1 00:25:08.415333 systemd[1]: Queued start job for default target multi-user.target. Nov 1 00:25:08.438598 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 1 00:25:08.438889 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 1 00:25:08.700540 systemd[1]: Started systemd-journald.service - Journal Service. Nov 1 00:25:08.701550 kernel: ACPI: bus type drm_connector registered Nov 1 00:25:08.702473 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 1 00:25:08.702595 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 1 00:25:08.703202 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 1 00:25:08.709680 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 1 00:25:08.715579 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 1 00:25:08.718555 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 1 00:25:08.719093 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 1 00:25:08.719160 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 1 00:25:08.720351 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Nov 1 00:25:08.721651 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 1 00:25:08.724651 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 1 00:25:08.725343 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 1 00:25:08.727130 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 1 00:25:08.729674 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 1 00:25:08.732039 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 1 00:25:08.734581 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 1 00:25:08.735082 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 1 00:25:08.735835 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 1 00:25:08.737794 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 1 00:25:08.740652 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 1 00:25:08.749155 systemd-journald[1127]: Time spent on flushing to /var/log/journal/30c52de4ab904d3c9c367688b90485bc is 17.255ms for 1111 entries. Nov 1 00:25:08.749155 systemd-journald[1127]: System Journal (/var/log/journal/30c52de4ab904d3c9c367688b90485bc) is 8.0M, max 584.8M, 576.8M free. Nov 1 00:25:08.782583 systemd-journald[1127]: Received client request to flush runtime journal. Nov 1 00:25:08.782621 kernel: loop0: detected capacity change from 0 to 229808 Nov 1 00:25:08.742622 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 1 00:25:08.745804 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 1 00:25:08.747634 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 1 00:25:08.754582 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 1 00:25:08.766692 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 1 00:25:08.769037 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 1 00:25:08.776495 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Nov 1 00:25:08.787528 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 1 00:25:08.788283 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 1 00:25:08.796939 udevadm[1177]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 1 00:25:08.806846 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 1 00:25:08.808786 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Nov 1 00:25:08.814779 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 1 00:25:08.820955 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 1 00:25:08.827662 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 1 00:25:08.844548 kernel: loop1: detected capacity change from 0 to 8 Nov 1 00:25:08.853481 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. Nov 1 00:25:08.853497 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. Nov 1 00:25:08.862675 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 1 00:25:08.867526 kernel: loop2: detected capacity change from 0 to 142488 Nov 1 00:25:08.915079 kernel: loop3: detected capacity change from 0 to 140768 Nov 1 00:25:08.960542 kernel: loop4: detected capacity change from 0 to 229808 Nov 1 00:25:08.982586 kernel: loop5: detected capacity change from 0 to 8 Nov 1 00:25:08.986586 kernel: loop6: detected capacity change from 0 to 142488 Nov 1 00:25:09.015089 kernel: loop7: detected capacity change from 0 to 140768 Nov 1 00:25:09.044672 (sd-merge)[1200]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-hetzner'. Nov 1 00:25:09.045172 (sd-merge)[1200]: Merged extensions into '/usr'. Nov 1 00:25:09.052620 systemd[1]: Reloading requested from client PID 1175 ('systemd-sysext') (unit systemd-sysext.service)... Nov 1 00:25:09.052643 systemd[1]: Reloading... Nov 1 00:25:09.157555 zram_generator::config[1225]: No configuration found. Nov 1 00:25:09.198242 ldconfig[1170]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 1 00:25:09.263071 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 1 00:25:09.302664 systemd[1]: Reloading finished in 249 ms. Nov 1 00:25:09.327120 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 1 00:25:09.327962 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 1 00:25:09.328670 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 1 00:25:09.335649 systemd[1]: Starting ensure-sysext.service... Nov 1 00:25:09.337083 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 1 00:25:09.340636 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 1 00:25:09.344915 systemd[1]: Reloading requested from client PID 1270 ('systemctl') (unit ensure-sysext.service)... Nov 1 00:25:09.344930 systemd[1]: Reloading... Nov 1 00:25:09.359485 systemd-tmpfiles[1272]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 1 00:25:09.360752 systemd-tmpfiles[1272]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 1 00:25:09.361301 systemd-tmpfiles[1272]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 1 00:25:09.361500 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Nov 1 00:25:09.361566 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Nov 1 00:25:09.365699 systemd-tmpfiles[1272]: Detected autofs mount point /boot during canonicalization of boot. Nov 1 00:25:09.365763 systemd-tmpfiles[1272]: Skipping /boot Nov 1 00:25:09.366676 systemd-udevd[1273]: Using default interface naming scheme 'v255'. Nov 1 00:25:09.371284 systemd-tmpfiles[1272]: Detected autofs mount point /boot during canonicalization of boot. Nov 1 00:25:09.371291 systemd-tmpfiles[1272]: Skipping /boot Nov 1 00:25:09.411528 zram_generator::config[1299]: No configuration found. Nov 1 00:25:09.520686 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (1302) Nov 1 00:25:09.526527 kernel: mousedev: PS/2 mouse device common for all mice Nov 1 00:25:09.526574 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Nov 1 00:25:09.548915 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 1 00:25:09.563579 kernel: ACPI: button: Power Button [PWRF] Nov 1 00:25:09.606527 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Nov 1 00:25:09.606754 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Nov 1 00:25:09.606879 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Nov 1 00:25:09.615000 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - QEMU_HARDDISK OEM. Nov 1 00:25:09.615896 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 1 00:25:09.616320 systemd[1]: Reloading finished in 271 ms. Nov 1 00:25:09.620902 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Nov 1 00:25:09.627993 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 1 00:25:09.632640 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 1 00:25:09.633526 kernel: EDAC MC: Ver: 3.0.0 Nov 1 00:25:09.650760 systemd[1]: Condition check resulted in dev-virtio\x2dports-org.qemu.guest_agent.0.device - /dev/virtio-ports/org.qemu.guest_agent.0 being skipped. Nov 1 00:25:09.670106 systemd[1]: Finished ensure-sysext.service. Nov 1 00:25:09.671522 kernel: [drm] pci: virtio-vga detected at 0000:00:01.0 Nov 1 00:25:09.675548 kernel: virtio-pci 0000:00:01.0: vgaarb: deactivate vga console Nov 1 00:25:09.674569 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 00:25:09.678567 kernel: Console: switching to colour dummy device 80x25 Nov 1 00:25:09.678605 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Nov 1 00:25:09.678620 kernel: [drm] features: -context_init Nov 1 00:25:09.678631 kernel: [drm] number of scanouts: 1 Nov 1 00:25:09.680282 kernel: [drm] number of cap sets: 0 Nov 1 00:25:09.680659 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Nov 1 00:25:09.684804 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:01.0 on minor 0 Nov 1 00:25:09.684840 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Nov 1 00:25:09.684859 kernel: Console: switching to colour frame buffer device 160x50 Nov 1 00:25:09.683617 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 1 00:25:09.683754 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 1 00:25:09.684795 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 1 00:25:09.699008 kernel: virtio-pci 0000:00:01.0: [drm] fb0: virtio_gpudrmfb frame buffer device Nov 1 00:25:09.707636 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 1 00:25:09.710709 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 1 00:25:09.714674 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 1 00:25:09.714832 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 1 00:25:09.716660 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 1 00:25:09.720444 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 1 00:25:09.722649 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 1 00:25:09.725206 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 1 00:25:09.732836 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Nov 1 00:25:09.735449 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 1 00:25:09.738630 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 00:25:09.738689 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 1 00:25:09.739172 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 1 00:25:09.739571 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 1 00:25:09.739853 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 1 00:25:09.739949 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 1 00:25:09.740161 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 1 00:25:09.740251 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 1 00:25:09.742608 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 1 00:25:09.745713 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 1 00:25:09.746008 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 1 00:25:09.753862 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 1 00:25:09.754012 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 1 00:25:09.759823 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 1 00:25:09.763709 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 1 00:25:09.763838 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 00:25:09.772577 augenrules[1418]: No rules Nov 1 00:25:09.772783 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 1 00:25:09.773285 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Nov 1 00:25:09.780538 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 1 00:25:09.791334 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 1 00:25:09.802648 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 1 00:25:09.803131 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 1 00:25:09.825586 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 1 00:25:09.833471 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 1 00:25:09.843674 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 1 00:25:09.856076 lvm[1441]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 1 00:25:09.879087 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 1 00:25:09.879665 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 1 00:25:09.881746 systemd-networkd[1399]: lo: Link UP Nov 1 00:25:09.881757 systemd-networkd[1399]: lo: Gained carrier Nov 1 00:25:09.886726 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 1 00:25:09.888900 systemd-networkd[1399]: Enumeration completed Nov 1 00:25:09.889228 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 1 00:25:09.889859 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 1 00:25:09.892308 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:09.892315 systemd-networkd[1399]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 1 00:25:09.894469 systemd-networkd[1399]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:09.894480 systemd-networkd[1399]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 1 00:25:09.896607 systemd-networkd[1399]: eth0: Link UP Nov 1 00:25:09.896618 systemd-networkd[1399]: eth0: Gained carrier Nov 1 00:25:09.896630 systemd-networkd[1399]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:09.901621 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 1 00:25:09.905586 lvm[1446]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 1 00:25:09.901713 systemd-networkd[1399]: eth1: Link UP Nov 1 00:25:09.901716 systemd-networkd[1399]: eth1: Gained carrier Nov 1 00:25:09.903534 systemd-networkd[1399]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 1 00:25:09.916312 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Nov 1 00:25:09.919275 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 1 00:25:09.920371 systemd[1]: Reached target time-set.target - System Time Set. Nov 1 00:25:09.922093 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 1 00:25:09.929328 systemd-resolved[1400]: Positive Trust Anchors: Nov 1 00:25:09.929341 systemd-resolved[1400]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 1 00:25:09.929366 systemd-resolved[1400]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 1 00:25:09.932673 systemd-resolved[1400]: Using system hostname 'ci-4081-3-6-n-c50cca3c34'. Nov 1 00:25:09.934002 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 1 00:25:09.934578 systemd[1]: Reached target network.target - Network. Nov 1 00:25:09.934945 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 1 00:25:09.935337 systemd[1]: Reached target sysinit.target - System Initialization. Nov 1 00:25:09.938154 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 1 00:25:09.938537 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 1 00:25:09.939004 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 1 00:25:09.939366 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 1 00:25:09.940727 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 1 00:25:09.941098 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 1 00:25:09.941167 systemd[1]: Reached target paths.target - Path Units. Nov 1 00:25:09.941542 systemd[1]: Reached target timers.target - Timer Units. Nov 1 00:25:09.942309 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 1 00:25:09.944034 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 1 00:25:09.950556 systemd-networkd[1399]: eth1: DHCPv4 address 10.0.0.4/32 acquired from 10.0.0.1 Nov 1 00:25:09.951927 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Nov 1 00:25:09.952935 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 1 00:25:09.954051 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 1 00:25:09.954973 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 1 00:25:09.956987 systemd[1]: Reached target sockets.target - Socket Units. Nov 1 00:25:09.957326 systemd[1]: Reached target basic.target - Basic System. Nov 1 00:25:09.957695 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 1 00:25:09.957715 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 1 00:25:09.961624 systemd[1]: Starting containerd.service - containerd container runtime... Nov 1 00:25:09.965343 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 1 00:25:09.968664 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 1 00:25:09.971075 systemd-networkd[1399]: eth0: DHCPv4 address 46.62.212.147/32, gateway 172.31.1.1 acquired from 172.31.1.1 Nov 1 00:25:09.972118 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 1 00:25:09.974632 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Nov 1 00:25:09.975930 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 1 00:25:09.976286 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 1 00:25:09.978796 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 1 00:25:09.982987 systemd[1]: Started qemu-guest-agent.service - QEMU Guest Agent. Nov 1 00:25:09.987729 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 1 00:25:09.992122 jq[1459]: false Nov 1 00:25:09.993064 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 1 00:25:10.001650 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 1 00:25:10.004280 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 1 00:25:10.005291 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 1 00:25:10.010264 extend-filesystems[1460]: Found loop4 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found loop5 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found loop6 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found loop7 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda1 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda2 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda3 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found usr Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda4 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda6 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda7 Nov 1 00:25:10.010264 extend-filesystems[1460]: Found sda9 Nov 1 00:25:10.010264 extend-filesystems[1460]: Checking size of /dev/sda9 Nov 1 00:25:10.011723 systemd[1]: Starting update-engine.service - Update Engine... Nov 1 00:25:10.049467 coreos-metadata[1455]: Nov 01 00:25:10.020 INFO Fetching http://169.254.169.254/hetzner/v1/metadata: Attempt #1 Nov 1 00:25:10.049467 coreos-metadata[1455]: Nov 01 00:25:10.024 INFO Fetch successful Nov 1 00:25:10.049467 coreos-metadata[1455]: Nov 01 00:25:10.026 INFO Fetching http://169.254.169.254/hetzner/v1/metadata/private-networks: Attempt #1 Nov 1 00:25:10.049467 coreos-metadata[1455]: Nov 01 00:25:10.029 INFO Fetch successful Nov 1 00:25:10.019600 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 1 00:25:10.035654 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 1 00:25:10.035820 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 1 00:25:10.036048 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 1 00:25:10.036158 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 1 00:25:10.062501 jq[1471]: true Nov 1 00:25:10.054369 systemd[1]: motdgen.service: Deactivated successfully. Nov 1 00:25:10.054638 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 1 00:25:10.068702 (ntainerd)[1481]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 1 00:25:10.079985 jq[1482]: true Nov 1 00:25:10.084034 extend-filesystems[1460]: Resized partition /dev/sda9 Nov 1 00:25:10.088605 extend-filesystems[1498]: resize2fs 1.47.1 (20-May-2024) Nov 1 00:25:10.100943 kernel: EXT4-fs (sda9): resizing filesystem from 1617920 to 9393147 blocks Nov 1 00:25:10.090585 dbus-daemon[1456]: [system] SELinux support is enabled Nov 1 00:25:10.095207 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 1 00:25:10.107808 update_engine[1468]: I20251101 00:25:10.102051 1468 main.cc:92] Flatcar Update Engine starting Nov 1 00:25:10.103165 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 1 00:25:10.103197 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 1 00:25:10.105530 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 1 00:25:10.105545 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 1 00:25:10.121748 update_engine[1468]: I20251101 00:25:10.121563 1468 update_check_scheduler.cc:74] Next update check in 11m14s Nov 1 00:25:10.121645 systemd[1]: Started update-engine.service - Update Engine. Nov 1 00:25:10.127191 systemd-logind[1466]: New seat seat0. Nov 1 00:25:10.134324 systemd-logind[1466]: Watching system buttons on /dev/input/event2 (Power Button) Nov 1 00:25:10.136221 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 1 00:25:10.140802 systemd-logind[1466]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 1 00:25:10.146887 systemd[1]: Started systemd-logind.service - User Login Management. Nov 1 00:25:10.161234 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 1 00:25:10.173753 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 1 00:25:10.216894 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (1310) Nov 1 00:25:10.273029 bash[1519]: Updated "/home/core/.ssh/authorized_keys" Nov 1 00:25:10.274261 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 1 00:25:10.288410 systemd[1]: Starting sshkeys.service... Nov 1 00:25:10.298878 sshd_keygen[1484]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 1 00:25:10.304877 locksmithd[1506]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 1 00:25:10.308724 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Nov 1 00:25:10.317734 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Nov 1 00:25:10.328625 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 1 00:25:10.337525 coreos-metadata[1539]: Nov 01 00:25:10.336 INFO Fetching http://169.254.169.254/hetzner/v1/metadata/public-keys: Attempt #1 Nov 1 00:25:10.341762 coreos-metadata[1539]: Nov 01 00:25:10.340 INFO Fetch successful Nov 1 00:25:10.341785 containerd[1481]: time="2025-11-01T00:25:10.337888288Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Nov 1 00:25:10.370179 containerd[1481]: time="2025-11-01T00:25:10.358033502Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 1 00:25:10.370179 containerd[1481]: time="2025-11-01T00:25:10.359032105Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 1 00:25:10.370179 containerd[1481]: time="2025-11-01T00:25:10.359053195Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 1 00:25:10.370179 containerd[1481]: time="2025-11-01T00:25:10.359101615Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 1 00:25:10.343675 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 1 00:25:10.348834 systemd[1]: issuegen.service: Deactivated successfully. Nov 1 00:25:10.349146 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 1 00:25:10.360970 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371094944Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371116123Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371167660Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371179242Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371294648Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371306861Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371316619Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371323723Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371652 containerd[1481]: time="2025-11-01T00:25:10.371392722Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 1 00:25:10.371601 unknown[1539]: wrote ssh authorized keys file for user: core Nov 1 00:25:10.403419 containerd[1481]: time="2025-11-01T00:25:10.372604474Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 1 00:25:10.403419 containerd[1481]: time="2025-11-01T00:25:10.372721243Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 1 00:25:10.403419 containerd[1481]: time="2025-11-01T00:25:10.372735420Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 1 00:25:10.403419 containerd[1481]: time="2025-11-01T00:25:10.382563739Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 1 00:25:10.403419 containerd[1481]: time="2025-11-01T00:25:10.382613152Z" level=info msg="metadata content store policy set" policy=shared Nov 1 00:25:10.375536 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 1 00:25:10.383760 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 1 00:25:10.386039 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 1 00:25:10.392392 systemd[1]: Reached target getty.target - Login Prompts. Nov 1 00:25:10.423541 kernel: EXT4-fs (sda9): resized filesystem to 9393147 Nov 1 00:25:10.451172 containerd[1481]: time="2025-11-01T00:25:10.451109439Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 1 00:25:10.451254 containerd[1481]: time="2025-11-01T00:25:10.451190822Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 1 00:25:10.451254 containerd[1481]: time="2025-11-01T00:25:10.451219786Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 1 00:25:10.451254 containerd[1481]: time="2025-11-01T00:25:10.451238992Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 1 00:25:10.451409 containerd[1481]: time="2025-11-01T00:25:10.451256585Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 1 00:25:10.451602 containerd[1481]: time="2025-11-01T00:25:10.451562379Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 1 00:25:10.451962 containerd[1481]: time="2025-11-01T00:25:10.451911543Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 1 00:25:10.452075 containerd[1481]: time="2025-11-01T00:25:10.452048540Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 1 00:25:10.452171 containerd[1481]: time="2025-11-01T00:25:10.452073968Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 1 00:25:10.452171 containerd[1481]: time="2025-11-01T00:25:10.452090709Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 1 00:25:10.452171 containerd[1481]: time="2025-11-01T00:25:10.452106789Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452171 containerd[1481]: time="2025-11-01T00:25:10.452122218Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452171 containerd[1481]: time="2025-11-01T00:25:10.452136796Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452171 containerd[1481]: time="2025-11-01T00:25:10.452159879Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452178163Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452193792Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452206276Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452216385Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452233758Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452249938Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452261059Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452274824Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452287 containerd[1481]: time="2025-11-01T00:25:10.452288380Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452306945Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452317695Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452329397Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452350156Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452363441Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452373680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452400450Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452412001Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452425507Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452446136Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452456706Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452497 containerd[1481]: time="2025-11-01T00:25:10.452466965Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452556342Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452576971Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452586749Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452598522Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452619811Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452630962Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452640290Z" level=info msg="NRI interface is disabled by configuration." Nov 1 00:25:10.452838 containerd[1481]: time="2025-11-01T00:25:10.452649708Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 1 00:25:10.453234 extend-filesystems[1498]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Nov 1 00:25:10.453234 extend-filesystems[1498]: old_desc_blocks = 1, new_desc_blocks = 5 Nov 1 00:25:10.453234 extend-filesystems[1498]: The filesystem on /dev/sda9 is now 9393147 (4k) blocks long. Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.452951273Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453008701Z" level=info msg="Connect containerd service" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453036272Z" level=info msg="using legacy CRI server" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453042213Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453120200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453718952Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453873192Z" level=info msg="Start subscribing containerd event" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453915420Z" level=info msg="Start recovering state" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453966747Z" level=info msg="Start event monitor" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453983649Z" level=info msg="Start snapshots syncer" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453991694Z" level=info msg="Start cni network conf syncer for default" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.453998176Z" level=info msg="Start streaming server" Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.456156804Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.456205024Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 1 00:25:10.459377 containerd[1481]: time="2025-11-01T00:25:10.456255699Z" level=info msg="containerd successfully booted in 0.119053s" Nov 1 00:25:10.457460 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 1 00:25:10.459899 extend-filesystems[1460]: Resized filesystem in /dev/sda9 Nov 1 00:25:10.459899 extend-filesystems[1460]: Found sr0 Nov 1 00:25:10.457638 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 1 00:25:10.459601 systemd[1]: Started containerd.service - containerd container runtime. Nov 1 00:25:10.475792 update-ssh-keys[1556]: Updated "/home/core/.ssh/authorized_keys" Nov 1 00:25:10.476415 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Nov 1 00:25:10.479057 systemd[1]: Finished sshkeys.service. Nov 1 00:25:11.026718 systemd-networkd[1399]: eth0: Gained IPv6LL Nov 1 00:25:11.027370 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Nov 1 00:25:11.029189 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 1 00:25:11.030089 systemd[1]: Reached target network-online.target - Network is Online. Nov 1 00:25:11.045854 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 1 00:25:11.049549 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 1 00:25:11.076323 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 1 00:25:11.794758 systemd-networkd[1399]: eth1: Gained IPv6LL Nov 1 00:25:11.795951 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Nov 1 00:25:12.345753 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 1 00:25:12.345939 (kubelet)[1579]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Nov 1 00:25:12.349456 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 1 00:25:12.351025 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 1 00:25:12.355091 systemd[1]: Started sshd@0-46.62.212.147:22-147.75.109.163:39418.service - OpenSSH per-connection server daemon (147.75.109.163:39418). Nov 1 00:25:12.360438 systemd[1]: Startup finished in 1.347s (kernel) + 5.207s (initrd) + 4.434s (userspace) = 10.988s. Nov 1 00:25:13.322444 kubelet[1579]: E1101 00:25:13.322325 1579 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Nov 1 00:25:13.326574 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Nov 1 00:25:13.326810 systemd[1]: kubelet.service: Failed with result 'exit-code'. Nov 1 00:25:13.327261 systemd[1]: kubelet.service: Consumed 1.541s CPU time. Nov 1 00:25:13.395594 sshd[1581]: Accepted publickey for core from 147.75.109.163 port 39418 ssh2: RSA SHA256:KMkO2BRQK4zvHgtpo4/QlyEdSpVbdU7AAfefKOV9vEE Nov 1 00:25:13.398133 sshd[1581]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 00:25:13.420462 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 1 00:25:13.428231 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 1 00:25:13.433613 systemd-logind[1466]: New session 1 of user core. Nov 1 00:25:13.445912 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 1 00:25:13.452231 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 1 00:25:13.463144 (systemd)[1596]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 1 00:25:13.591220 systemd[1596]: Queued start job for default target default.target. Nov 1 00:25:13.600245 systemd[1596]: Created slice app.slice - User Application Slice. Nov 1 00:25:13.600267 systemd[1596]: Reached target paths.target - Paths. Nov 1 00:25:13.600278 systemd[1596]: Reached target timers.target - Timers. Nov 1 00:25:13.601333 systemd[1596]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 1 00:25:13.610727 systemd[1596]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 1 00:25:13.610767 systemd[1596]: Reached target sockets.target - Sockets. Nov 1 00:25:13.610778 systemd[1596]: Reached target basic.target - Basic System. Nov 1 00:25:13.610813 systemd[1596]: Reached target default.target - Main User Target. Nov 1 00:25:13.610850 systemd[1596]: Startup finished in 138ms. Nov 1 00:25:13.611027 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 1 00:25:13.617654 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 1 00:25:14.323369 systemd[1]: Started sshd@1-46.62.212.147:22-147.75.109.163:39432.service - OpenSSH per-connection server daemon (147.75.109.163:39432). Nov 1 00:25:15.319045 sshd[1607]: Accepted publickey for core from 147.75.109.163 port 39432 ssh2: RSA SHA256:KMkO2BRQK4zvHgtpo4/QlyEdSpVbdU7AAfefKOV9vEE Nov 1 00:25:15.321136 sshd[1607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 00:25:15.328962 systemd-logind[1466]: New session 2 of user core. Nov 1 00:25:15.334798 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 1 00:25:16.014645 sshd[1607]: pam_unix(sshd:session): session closed for user core Nov 1 00:25:16.018144 systemd[1]: sshd@1-46.62.212.147:22-147.75.109.163:39432.service: Deactivated successfully. Nov 1 00:25:16.020430 systemd[1]: session-2.scope: Deactivated successfully. Nov 1 00:25:16.022260 systemd-logind[1466]: Session 2 logged out. Waiting for processes to exit. Nov 1 00:25:16.024097 systemd-logind[1466]: Removed session 2. Nov 1 00:25:16.219948 systemd[1]: Started sshd@2-46.62.212.147:22-147.75.109.163:39444.service - OpenSSH per-connection server daemon (147.75.109.163:39444). Nov 1 00:25:17.323952 sshd[1614]: Accepted publickey for core from 147.75.109.163 port 39444 ssh2: RSA SHA256:KMkO2BRQK4zvHgtpo4/QlyEdSpVbdU7AAfefKOV9vEE Nov 1 00:25:17.325478 sshd[1614]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 00:25:17.331161 systemd-logind[1466]: New session 3 of user core. Nov 1 00:25:17.336726 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 1 00:25:18.078289 sshd[1614]: pam_unix(sshd:session): session closed for user core Nov 1 00:25:18.082004 systemd[1]: sshd@2-46.62.212.147:22-147.75.109.163:39444.service: Deactivated successfully. Nov 1 00:25:18.084897 systemd[1]: session-3.scope: Deactivated successfully. Nov 1 00:25:18.086947 systemd-logind[1466]: Session 3 logged out. Waiting for processes to exit. Nov 1 00:25:18.088246 systemd-logind[1466]: Removed session 3. Nov 1 00:25:18.236923 systemd[1]: Started sshd@3-46.62.212.147:22-147.75.109.163:39454.service - OpenSSH per-connection server daemon (147.75.109.163:39454). Nov 1 00:25:19.227975 sshd[1621]: Accepted publickey for core from 147.75.109.163 port 39454 ssh2: RSA SHA256:KMkO2BRQK4zvHgtpo4/QlyEdSpVbdU7AAfefKOV9vEE Nov 1 00:25:19.229366 sshd[1621]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 00:25:19.234192 systemd-logind[1466]: New session 4 of user core. Nov 1 00:25:19.241652 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 1 00:25:19.918941 sshd[1621]: pam_unix(sshd:session): session closed for user core Nov 1 00:25:19.921699 systemd[1]: sshd@3-46.62.212.147:22-147.75.109.163:39454.service: Deactivated successfully. Nov 1 00:25:19.923497 systemd[1]: session-4.scope: Deactivated successfully. Nov 1 00:25:19.925255 systemd-logind[1466]: Session 4 logged out. Waiting for processes to exit. Nov 1 00:25:19.926733 systemd-logind[1466]: Removed session 4. Nov 1 00:25:20.129750 systemd[1]: Started sshd@4-46.62.212.147:22-147.75.109.163:41130.service - OpenSSH per-connection server daemon (147.75.109.163:41130). Nov 1 00:25:21.232267 sshd[1628]: Accepted publickey for core from 147.75.109.163 port 41130 ssh2: RSA SHA256:KMkO2BRQK4zvHgtpo4/QlyEdSpVbdU7AAfefKOV9vEE Nov 1 00:25:21.233827 sshd[1628]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 1 00:25:21.238056 systemd-logind[1466]: New session 5 of user core. Nov 1 00:25:21.248663 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 1 00:25:21.831330 sudo[1631]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Nov 1 00:25:21.831873 sudo[1631]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 1 00:25:22.769710 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 1 00:25:22.770065 systemd[1]: kubelet.service: Consumed 1.541s CPU time. Nov 1 00:25:22.778305 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 1 00:25:22.818835 systemd[1]: Reloading requested from client PID 1665 ('systemctl') (unit session-5.scope)... Nov 1 00:25:22.819019 systemd[1]: Reloading... Nov 1 00:25:22.950541 zram_generator::config[1704]: No configuration found. Nov 1 00:25:23.038874 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 1 00:25:23.102591 systemd[1]: Reloading finished in 282 ms. Nov 1 00:25:23.142972 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 1 00:25:23.145807 (kubelet)[1750]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 1 00:25:23.149891 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Nov 1 00:25:23.150364 systemd[1]: kubelet.service: Deactivated successfully. Nov 1 00:25:23.150940 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Nov 1 00:25:23.157906 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Nov 1 00:25:23.229801 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Nov 1 00:25:23.239989 (kubelet)[1763]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Nov 1 00:25:23.303941 kubelet[1763]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 1 00:25:23.303941 kubelet[1763]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Nov 1 00:25:23.303941 kubelet[1763]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Nov 1 00:25:23.304704 kubelet[1763]: I1101 00:25:23.303904 1763 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Nov 1 00:25:23.772625 kubelet[1763]: I1101 00:25:23.771763 1763 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Nov 1 00:25:23.772625 kubelet[1763]: I1101 00:25:23.771816 1763 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Nov 1 00:25:23.772625 kubelet[1763]: I1101 00:25:23.772162 1763 server.go:956] "Client rotation is on, will bootstrap in background" Nov 1 00:25:23.798970 kubelet[1763]: I1101 00:25:23.798947 1763 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Nov 1 00:25:23.808994 kubelet[1763]: E1101 00:25:23.808949 1763 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Nov 1 00:25:23.809139 kubelet[1763]: I1101 00:25:23.809104 1763 server.go:1423] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Nov 1 00:25:23.812315 kubelet[1763]: I1101 00:25:23.812285 1763 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Nov 1 00:25:23.812518 kubelet[1763]: I1101 00:25:23.812465 1763 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Nov 1 00:25:23.812695 kubelet[1763]: I1101 00:25:23.812493 1763 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.4","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":"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},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Nov 1 00:25:23.812695 kubelet[1763]: I1101 00:25:23.812691 1763 topology_manager.go:138] "Creating topology manager with none policy" Nov 1 00:25:23.812788 kubelet[1763]: I1101 00:25:23.812700 1763 container_manager_linux.go:303] "Creating device plugin manager" Nov 1 00:25:23.812842 kubelet[1763]: I1101 00:25:23.812816 1763 state_mem.go:36] "Initialized new in-memory state store" Nov 1 00:25:23.815744 kubelet[1763]: I1101 00:25:23.815719 1763 kubelet.go:480] "Attempting to sync node with API server" Nov 1 00:25:23.815744 kubelet[1763]: I1101 00:25:23.815746 1763 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Nov 1 00:25:23.815801 kubelet[1763]: I1101 00:25:23.815770 1763 kubelet.go:386] "Adding apiserver pod source" Nov 1 00:25:23.817834 kubelet[1763]: I1101 00:25:23.817543 1763 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Nov 1 00:25:23.820110 kubelet[1763]: E1101 00:25:23.820023 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:23.820110 kubelet[1763]: E1101 00:25:23.820054 1763 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:23.822187 kubelet[1763]: I1101 00:25:23.822169 1763 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Nov 1 00:25:23.822625 kubelet[1763]: I1101 00:25:23.822603 1763 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Nov 1 00:25:23.823286 kubelet[1763]: W1101 00:25:23.823267 1763 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Nov 1 00:25:23.825457 kubelet[1763]: I1101 00:25:23.825428 1763 watchdog_linux.go:99] "Systemd watchdog is not enabled" Nov 1 00:25:23.825496 kubelet[1763]: I1101 00:25:23.825484 1763 server.go:1289] "Started kubelet" Nov 1 00:25:23.826595 kubelet[1763]: I1101 00:25:23.825558 1763 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Nov 1 00:25:23.826595 kubelet[1763]: I1101 00:25:23.826261 1763 server.go:317] "Adding debug handlers to kubelet server" Nov 1 00:25:23.834195 kubelet[1763]: I1101 00:25:23.834133 1763 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Nov 1 00:25:23.834406 kubelet[1763]: I1101 00:25:23.834379 1763 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Nov 1 00:25:23.834827 kubelet[1763]: I1101 00:25:23.834815 1763 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Nov 1 00:25:23.836301 kubelet[1763]: I1101 00:25:23.836273 1763 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Nov 1 00:25:23.838196 kubelet[1763]: I1101 00:25:23.838186 1763 volume_manager.go:297] "Starting Kubelet Volume Manager" Nov 1 00:25:23.838536 kubelet[1763]: E1101 00:25:23.838378 1763 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Nov 1 00:25:23.839158 kubelet[1763]: I1101 00:25:23.839148 1763 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Nov 1 00:25:23.839272 kubelet[1763]: I1101 00:25:23.839264 1763 reconciler.go:26] "Reconciler: start to sync state" Nov 1 00:25:23.843295 kubelet[1763]: I1101 00:25:23.843282 1763 factory.go:223] Registration of the systemd container factory successfully Nov 1 00:25:23.843616 kubelet[1763]: I1101 00:25:23.843401 1763 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Nov 1 00:25:23.851254 kubelet[1763]: I1101 00:25:23.850890 1763 factory.go:223] Registration of the containerd container factory successfully Nov 1 00:25:23.851816 kubelet[1763]: E1101 00:25:23.851741 1763 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Nov 1 00:25:23.853990 kubelet[1763]: E1101 00:25:23.853888 1763 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.4\" not found" node="10.0.0.4" Nov 1 00:25:23.859399 kubelet[1763]: I1101 00:25:23.859389 1763 cpu_manager.go:221] "Starting CPU manager" policy="none" Nov 1 00:25:23.859655 kubelet[1763]: I1101 00:25:23.859477 1763 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Nov 1 00:25:23.859655 kubelet[1763]: I1101 00:25:23.859493 1763 state_mem.go:36] "Initialized new in-memory state store" Nov 1 00:25:23.861548 kubelet[1763]: I1101 00:25:23.861409 1763 policy_none.go:49] "None policy: Start" Nov 1 00:25:23.861548 kubelet[1763]: I1101 00:25:23.861424 1763 memory_manager.go:186] "Starting memorymanager" policy="None" Nov 1 00:25:23.861548 kubelet[1763]: I1101 00:25:23.861433 1763 state_mem.go:35] "Initializing new in-memory state store" Nov 1 00:25:23.869126 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Nov 1 00:25:23.897416 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Nov 1 00:25:23.900967 kubelet[1763]: I1101 00:25:23.900860 1763 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Nov 1 00:25:23.902131 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Nov 1 00:25:23.902800 kubelet[1763]: I1101 00:25:23.902242 1763 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Nov 1 00:25:23.902800 kubelet[1763]: I1101 00:25:23.902257 1763 status_manager.go:230] "Starting to sync pod status with apiserver" Nov 1 00:25:23.902800 kubelet[1763]: I1101 00:25:23.902272 1763 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Nov 1 00:25:23.902800 kubelet[1763]: I1101 00:25:23.902277 1763 kubelet.go:2436] "Starting kubelet main sync loop" Nov 1 00:25:23.902800 kubelet[1763]: E1101 00:25:23.902300 1763 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Nov 1 00:25:23.908102 kubelet[1763]: E1101 00:25:23.908089 1763 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Nov 1 00:25:23.909743 kubelet[1763]: I1101 00:25:23.909734 1763 eviction_manager.go:189] "Eviction manager: starting control loop" Nov 1 00:25:23.909868 kubelet[1763]: I1101 00:25:23.909825 1763 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Nov 1 00:25:23.911179 kubelet[1763]: I1101 00:25:23.910347 1763 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Nov 1 00:25:23.911378 kubelet[1763]: E1101 00:25:23.911366 1763 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Nov 1 00:25:23.911685 kubelet[1763]: E1101 00:25:23.911674 1763 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.4\" not found" Nov 1 00:25:24.013202 kubelet[1763]: I1101 00:25:24.013111 1763 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.4" Nov 1 00:25:24.019399 kubelet[1763]: I1101 00:25:24.019331 1763 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.4" Nov 1 00:25:24.019399 kubelet[1763]: E1101 00:25:24.019367 1763 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.4\": node \"10.0.0.4\" not found" Nov 1 00:25:24.029036 kubelet[1763]: I1101 00:25:24.028359 1763 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Nov 1 00:25:24.031532 containerd[1481]: time="2025-11-01T00:25:24.029378737Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Nov 1 00:25:24.031908 kubelet[1763]: I1101 00:25:24.029772 1763 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Nov 1 00:25:24.264329 sudo[1631]: pam_unix(sudo:session): session closed for user root Nov 1 00:25:24.444023 sshd[1628]: pam_unix(sshd:session): session closed for user core Nov 1 00:25:24.447585 systemd[1]: sshd@4-46.62.212.147:22-147.75.109.163:41130.service: Deactivated successfully. Nov 1 00:25:24.450132 systemd[1]: session-5.scope: Deactivated successfully. Nov 1 00:25:24.452697 systemd-logind[1466]: Session 5 logged out. Waiting for processes to exit. Nov 1 00:25:24.454682 systemd-logind[1466]: Removed session 5. Nov 1 00:25:24.779045 kubelet[1763]: I1101 00:25:24.778283 1763 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Nov 1 00:25:24.779045 kubelet[1763]: I1101 00:25:24.778540 1763 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 1 00:25:24.779045 kubelet[1763]: I1101 00:25:24.778601 1763 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 1 00:25:24.779045 kubelet[1763]: I1101 00:25:24.778653 1763 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Nov 1 00:25:24.821118 kubelet[1763]: E1101 00:25:24.821081 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:24.822408 kubelet[1763]: I1101 00:25:24.822226 1763 apiserver.go:52] "Watching apiserver" Nov 1 00:25:24.840416 kubelet[1763]: I1101 00:25:24.840370 1763 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Nov 1 00:25:24.844169 systemd[1]: Created slice kubepods-besteffort-podb3702dfe_411e_423e_b971_9aa85cf94126.slice - libcontainer container kubepods-besteffort-podb3702dfe_411e_423e_b971_9aa85cf94126.slice. Nov 1 00:25:24.845224 kubelet[1763]: I1101 00:25:24.845133 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b3702dfe-411e-423e-b971-9aa85cf94126-xtables-lock\") pod \"kube-proxy-297p8\" (UID: \"b3702dfe-411e-423e-b971-9aa85cf94126\") " pod="kube-system/kube-proxy-297p8" Nov 1 00:25:24.850053 kubelet[1763]: I1101 00:25:24.849745 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b3702dfe-411e-423e-b971-9aa85cf94126-lib-modules\") pod \"kube-proxy-297p8\" (UID: \"b3702dfe-411e-423e-b971-9aa85cf94126\") " pod="kube-system/kube-proxy-297p8" Nov 1 00:25:24.850053 kubelet[1763]: I1101 00:25:24.849804 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/86b6acc1-70cb-47b8-bc6d-ccb528e51526-cni-plugin\") pod \"kube-flannel-ds-b6zzm\" (UID: \"86b6acc1-70cb-47b8-bc6d-ccb528e51526\") " pod="kube-flannel/kube-flannel-ds-b6zzm" Nov 1 00:25:24.850053 kubelet[1763]: I1101 00:25:24.849840 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/86b6acc1-70cb-47b8-bc6d-ccb528e51526-cni\") pod \"kube-flannel-ds-b6zzm\" (UID: \"86b6acc1-70cb-47b8-bc6d-ccb528e51526\") " pod="kube-flannel/kube-flannel-ds-b6zzm" Nov 1 00:25:24.850053 kubelet[1763]: I1101 00:25:24.849869 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/86b6acc1-70cb-47b8-bc6d-ccb528e51526-xtables-lock\") pod \"kube-flannel-ds-b6zzm\" (UID: \"86b6acc1-70cb-47b8-bc6d-ccb528e51526\") " pod="kube-flannel/kube-flannel-ds-b6zzm" Nov 1 00:25:24.850053 kubelet[1763]: I1101 00:25:24.849900 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qv9pv\" (UniqueName: \"kubernetes.io/projected/86b6acc1-70cb-47b8-bc6d-ccb528e51526-kube-api-access-qv9pv\") pod \"kube-flannel-ds-b6zzm\" (UID: \"86b6acc1-70cb-47b8-bc6d-ccb528e51526\") " pod="kube-flannel/kube-flannel-ds-b6zzm" Nov 1 00:25:24.850292 kubelet[1763]: I1101 00:25:24.850053 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b3702dfe-411e-423e-b971-9aa85cf94126-kube-proxy\") pod \"kube-proxy-297p8\" (UID: \"b3702dfe-411e-423e-b971-9aa85cf94126\") " pod="kube-system/kube-proxy-297p8" Nov 1 00:25:24.850292 kubelet[1763]: I1101 00:25:24.850086 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mzs7j\" (UniqueName: \"kubernetes.io/projected/b3702dfe-411e-423e-b971-9aa85cf94126-kube-api-access-mzs7j\") pod \"kube-proxy-297p8\" (UID: \"b3702dfe-411e-423e-b971-9aa85cf94126\") " pod="kube-system/kube-proxy-297p8" Nov 1 00:25:24.850292 kubelet[1763]: I1101 00:25:24.850116 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/86b6acc1-70cb-47b8-bc6d-ccb528e51526-run\") pod \"kube-flannel-ds-b6zzm\" (UID: \"86b6acc1-70cb-47b8-bc6d-ccb528e51526\") " pod="kube-flannel/kube-flannel-ds-b6zzm" Nov 1 00:25:24.850292 kubelet[1763]: I1101 00:25:24.850142 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/86b6acc1-70cb-47b8-bc6d-ccb528e51526-flannel-cfg\") pod \"kube-flannel-ds-b6zzm\" (UID: \"86b6acc1-70cb-47b8-bc6d-ccb528e51526\") " pod="kube-flannel/kube-flannel-ds-b6zzm" Nov 1 00:25:24.862584 systemd[1]: Created slice kubepods-burstable-pod86b6acc1_70cb_47b8_bc6d_ccb528e51526.slice - libcontainer container kubepods-burstable-pod86b6acc1_70cb_47b8_bc6d_ccb528e51526.slice. Nov 1 00:25:25.161162 containerd[1481]: time="2025-11-01T00:25:25.161050637Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-297p8,Uid:b3702dfe-411e-423e-b971-9aa85cf94126,Namespace:kube-system,Attempt:0,}" Nov 1 00:25:25.169320 containerd[1481]: time="2025-11-01T00:25:25.169288644Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-b6zzm,Uid:86b6acc1-70cb-47b8-bc6d-ccb528e51526,Namespace:kube-flannel,Attempt:0,}" Nov 1 00:25:25.671015 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2171619663.mount: Deactivated successfully. Nov 1 00:25:25.681800 containerd[1481]: time="2025-11-01T00:25:25.681744722Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 1 00:25:25.682947 containerd[1481]: time="2025-11-01T00:25:25.682901150Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 1 00:25:25.684060 containerd[1481]: time="2025-11-01T00:25:25.684024977Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312078" Nov 1 00:25:25.684870 containerd[1481]: time="2025-11-01T00:25:25.684829285Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Nov 1 00:25:25.687528 containerd[1481]: time="2025-11-01T00:25:25.685978340Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 1 00:25:25.689437 containerd[1481]: time="2025-11-01T00:25:25.689411267Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Nov 1 00:25:25.690319 containerd[1481]: time="2025-11-01T00:25:25.690288632Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 520.916201ms" Nov 1 00:25:25.692967 containerd[1481]: time="2025-11-01T00:25:25.692926548Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 531.797435ms" Nov 1 00:25:25.822281 kubelet[1763]: E1101 00:25:25.821784 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:25.824850 containerd[1481]: time="2025-11-01T00:25:25.824725946Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 1 00:25:25.825061 containerd[1481]: time="2025-11-01T00:25:25.825016671Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 1 00:25:25.825751 containerd[1481]: time="2025-11-01T00:25:25.825349445Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:25.830359 containerd[1481]: time="2025-11-01T00:25:25.829058029Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 1 00:25:25.830359 containerd[1481]: time="2025-11-01T00:25:25.829109475Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 1 00:25:25.830359 containerd[1481]: time="2025-11-01T00:25:25.829129052Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:25.830359 containerd[1481]: time="2025-11-01T00:25:25.829209794Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:25.830570 containerd[1481]: time="2025-11-01T00:25:25.828628704Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:25.899722 systemd[1]: Started cri-containerd-2de7b4eca153c0e66f06f546055dca3731e715122821be36fcb1b69a3d55f21b.scope - libcontainer container 2de7b4eca153c0e66f06f546055dca3731e715122821be36fcb1b69a3d55f21b. Nov 1 00:25:25.908323 systemd[1]: Started cri-containerd-dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9.scope - libcontainer container dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9. Nov 1 00:25:25.941432 containerd[1481]: time="2025-11-01T00:25:25.940678449Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-297p8,Uid:b3702dfe-411e-423e-b971-9aa85cf94126,Namespace:kube-system,Attempt:0,} returns sandbox id \"2de7b4eca153c0e66f06f546055dca3731e715122821be36fcb1b69a3d55f21b\"" Nov 1 00:25:25.943154 containerd[1481]: time="2025-11-01T00:25:25.943136217Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Nov 1 00:25:25.959599 containerd[1481]: time="2025-11-01T00:25:25.959566886Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-b6zzm,Uid:86b6acc1-70cb-47b8-bc6d-ccb528e51526,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9\"" Nov 1 00:25:26.822945 kubelet[1763]: E1101 00:25:26.822759 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:27.041297 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1490249763.mount: Deactivated successfully. Nov 1 00:25:27.373193 containerd[1481]: time="2025-11-01T00:25:27.373131845Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:27.374304 containerd[1481]: time="2025-11-01T00:25:27.374110720Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=31929497" Nov 1 00:25:27.376107 containerd[1481]: time="2025-11-01T00:25:27.375240839Z" level=info msg="ImageCreate event name:\"sha256:2844ee7bb56c2c194e1f4adafb9e7b60b9ed16aa4d07ab8ad1f019362e2efab3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:27.377917 containerd[1481]: time="2025-11-01T00:25:27.377247992Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:27.377917 containerd[1481]: time="2025-11-01T00:25:27.377760754Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:2844ee7bb56c2c194e1f4adafb9e7b60b9ed16aa4d07ab8ad1f019362e2efab3\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"31928488\" in 1.4342048s" Nov 1 00:25:27.377917 containerd[1481]: time="2025-11-01T00:25:27.377793516Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:2844ee7bb56c2c194e1f4adafb9e7b60b9ed16aa4d07ab8ad1f019362e2efab3\"" Nov 1 00:25:27.379531 containerd[1481]: time="2025-11-01T00:25:27.379132456Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Nov 1 00:25:27.381970 containerd[1481]: time="2025-11-01T00:25:27.381936033Z" level=info msg="CreateContainer within sandbox \"2de7b4eca153c0e66f06f546055dca3731e715122821be36fcb1b69a3d55f21b\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Nov 1 00:25:27.394369 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1912051995.mount: Deactivated successfully. Nov 1 00:25:27.402485 containerd[1481]: time="2025-11-01T00:25:27.402435341Z" level=info msg="CreateContainer within sandbox \"2de7b4eca153c0e66f06f546055dca3731e715122821be36fcb1b69a3d55f21b\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"b122f33f6d861f610f8805f67f50ca3f2d2fd6e92d83830df5bd7ee83a3eed93\"" Nov 1 00:25:27.403112 containerd[1481]: time="2025-11-01T00:25:27.402992386Z" level=info msg="StartContainer for \"b122f33f6d861f610f8805f67f50ca3f2d2fd6e92d83830df5bd7ee83a3eed93\"" Nov 1 00:25:27.428624 systemd[1]: Started cri-containerd-b122f33f6d861f610f8805f67f50ca3f2d2fd6e92d83830df5bd7ee83a3eed93.scope - libcontainer container b122f33f6d861f610f8805f67f50ca3f2d2fd6e92d83830df5bd7ee83a3eed93. Nov 1 00:25:27.455586 containerd[1481]: time="2025-11-01T00:25:27.455431254Z" level=info msg="StartContainer for \"b122f33f6d861f610f8805f67f50ca3f2d2fd6e92d83830df5bd7ee83a3eed93\" returns successfully" Nov 1 00:25:27.823419 kubelet[1763]: E1101 00:25:27.823224 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:28.823881 kubelet[1763]: E1101 00:25:28.823785 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:29.111313 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4271851112.mount: Deactivated successfully. Nov 1 00:25:29.175446 containerd[1481]: time="2025-11-01T00:25:29.175395853Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:29.176326 containerd[1481]: time="2025-11-01T00:25:29.176283548Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Nov 1 00:25:29.176954 containerd[1481]: time="2025-11-01T00:25:29.176932044Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:29.180732 containerd[1481]: time="2025-11-01T00:25:29.180699848Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:29.181815 containerd[1481]: time="2025-11-01T00:25:29.181764295Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 1.802608383s" Nov 1 00:25:29.181815 containerd[1481]: time="2025-11-01T00:25:29.181795794Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Nov 1 00:25:29.186875 containerd[1481]: time="2025-11-01T00:25:29.186832027Z" level=info msg="CreateContainer within sandbox \"dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Nov 1 00:25:29.204234 containerd[1481]: time="2025-11-01T00:25:29.204179355Z" level=info msg="CreateContainer within sandbox \"dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737\"" Nov 1 00:25:29.205455 containerd[1481]: time="2025-11-01T00:25:29.205404121Z" level=info msg="StartContainer for \"3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737\"" Nov 1 00:25:29.240738 systemd[1]: Started cri-containerd-3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737.scope - libcontainer container 3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737. Nov 1 00:25:29.278504 systemd[1]: cri-containerd-3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737.scope: Deactivated successfully. Nov 1 00:25:29.281150 containerd[1481]: time="2025-11-01T00:25:29.281100209Z" level=info msg="StartContainer for \"3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737\" returns successfully" Nov 1 00:25:29.392275 containerd[1481]: time="2025-11-01T00:25:29.391253588Z" level=info msg="shim disconnected" id=3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737 namespace=k8s.io Nov 1 00:25:29.392275 containerd[1481]: time="2025-11-01T00:25:29.391322497Z" level=warning msg="cleaning up after shim disconnected" id=3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737 namespace=k8s.io Nov 1 00:25:29.392275 containerd[1481]: time="2025-11-01T00:25:29.391337175Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 1 00:25:29.824654 kubelet[1763]: E1101 00:25:29.824451 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:29.939297 containerd[1481]: time="2025-11-01T00:25:29.939001261Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Nov 1 00:25:29.949295 kubelet[1763]: I1101 00:25:29.949204 1763 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-297p8" podStartSLOduration=3.513181725 podStartE2EDuration="4.949180879s" podCreationTimestamp="2025-11-01 00:25:25 +0000 UTC" firstStartedPulling="2025-11-01 00:25:25.942615571 +0000 UTC m=+2.697345669" lastFinishedPulling="2025-11-01 00:25:27.378614726 +0000 UTC m=+4.133344823" observedRunningTime="2025-11-01 00:25:27.947889033 +0000 UTC m=+4.702619171" watchObservedRunningTime="2025-11-01 00:25:29.949180879 +0000 UTC m=+6.703911047" Nov 1 00:25:29.957853 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3cbcd25892741f20a39892317682d16d38ed3117dd9f613b75206553787a0737-rootfs.mount: Deactivated successfully. Nov 1 00:25:30.824691 kubelet[1763]: E1101 00:25:30.824620 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:31.825145 kubelet[1763]: E1101 00:25:31.825113 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:32.412948 containerd[1481]: time="2025-11-01T00:25:32.412855299Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:32.414839 containerd[1481]: time="2025-11-01T00:25:32.414782653Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Nov 1 00:25:32.417655 containerd[1481]: time="2025-11-01T00:25:32.416336466Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:32.428004 containerd[1481]: time="2025-11-01T00:25:32.427961815Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:32.429752 containerd[1481]: time="2025-11-01T00:25:32.429706376Z" 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.49064821s" Nov 1 00:25:32.429843 containerd[1481]: time="2025-11-01T00:25:32.429755148Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Nov 1 00:25:32.436042 containerd[1481]: time="2025-11-01T00:25:32.436006590Z" level=info msg="CreateContainer within sandbox \"dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Nov 1 00:25:32.456744 containerd[1481]: time="2025-11-01T00:25:32.456673924Z" level=info msg="CreateContainer within sandbox \"dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a\"" Nov 1 00:25:32.457381 containerd[1481]: time="2025-11-01T00:25:32.457338670Z" level=info msg="StartContainer for \"760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a\"" Nov 1 00:25:32.492719 systemd[1]: run-containerd-runc-k8s.io-760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a-runc.JjqnMu.mount: Deactivated successfully. Nov 1 00:25:32.504692 systemd[1]: Started cri-containerd-760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a.scope - libcontainer container 760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a. Nov 1 00:25:32.540069 systemd[1]: cri-containerd-760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a.scope: Deactivated successfully. Nov 1 00:25:32.541745 containerd[1481]: time="2025-11-01T00:25:32.541679507Z" level=info msg="StartContainer for \"760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a\" returns successfully" Nov 1 00:25:32.551536 kubelet[1763]: I1101 00:25:32.550576 1763 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Nov 1 00:25:32.623881 containerd[1481]: time="2025-11-01T00:25:32.623735751Z" level=info msg="shim disconnected" id=760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a namespace=k8s.io Nov 1 00:25:32.623881 containerd[1481]: time="2025-11-01T00:25:32.623845718Z" level=warning msg="cleaning up after shim disconnected" id=760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a namespace=k8s.io Nov 1 00:25:32.623881 containerd[1481]: time="2025-11-01T00:25:32.623861538Z" level=info msg="cleaning up dead shim" namespace=k8s.io Nov 1 00:25:32.826592 kubelet[1763]: E1101 00:25:32.826322 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:32.951143 containerd[1481]: time="2025-11-01T00:25:32.951077070Z" level=info msg="CreateContainer within sandbox \"dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Nov 1 00:25:32.967852 containerd[1481]: time="2025-11-01T00:25:32.967779899Z" level=info msg="CreateContainer within sandbox \"dcf3eb6df47bd4b90dfe32b76e87d1123167954907cc35c50f90a6dc5a94f1b9\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"a1993f7408704a6b8e495971bf22ba765ee423ef4a44904431adc1be8e43ee5c\"" Nov 1 00:25:32.968464 containerd[1481]: time="2025-11-01T00:25:32.968420801Z" level=info msg="StartContainer for \"a1993f7408704a6b8e495971bf22ba765ee423ef4a44904431adc1be8e43ee5c\"" Nov 1 00:25:33.005746 systemd[1]: Started cri-containerd-a1993f7408704a6b8e495971bf22ba765ee423ef4a44904431adc1be8e43ee5c.scope - libcontainer container a1993f7408704a6b8e495971bf22ba765ee423ef4a44904431adc1be8e43ee5c. Nov 1 00:25:33.042315 containerd[1481]: time="2025-11-01T00:25:33.042228358Z" level=info msg="StartContainer for \"a1993f7408704a6b8e495971bf22ba765ee423ef4a44904431adc1be8e43ee5c\" returns successfully" Nov 1 00:25:33.447635 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-760f85cf6ffdbda2e0ee05b1ae89f0b444f14d223c45cc3155786a0a0267443a-rootfs.mount: Deactivated successfully. Nov 1 00:25:33.827132 kubelet[1763]: E1101 00:25:33.827003 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:34.108664 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Nov 1 00:25:34.121339 systemd-networkd[1399]: flannel.1: Link UP Nov 1 00:25:34.121358 systemd-networkd[1399]: flannel.1: Gained carrier Nov 1 00:25:35.484170 systemd-timesyncd[1402]: Contacted time server 78.47.93.191:123 (2.flatcar.pool.ntp.org). Nov 1 00:25:35.484236 systemd-timesyncd[1402]: Initial clock synchronization to Sat 2025-11-01 00:25:35.483963 UTC. Nov 1 00:25:35.484296 systemd-resolved[1400]: Clock change detected. Flushing caches. Nov 1 00:25:35.893166 kubelet[1763]: E1101 00:25:35.893076 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:36.571375 kubelet[1763]: I1101 00:25:36.571303 1763 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-b6zzm" podStartSLOduration=5.099621004 podStartE2EDuration="11.571284512s" podCreationTimestamp="2025-11-01 00:25:25 +0000 UTC" firstStartedPulling="2025-11-01 00:25:25.960376404 +0000 UTC m=+2.715106512" lastFinishedPulling="2025-11-01 00:25:32.432039902 +0000 UTC m=+9.186770020" observedRunningTime="2025-11-01 00:25:33.959988552 +0000 UTC m=+10.714718710" watchObservedRunningTime="2025-11-01 00:25:36.571284512 +0000 UTC m=+12.260910136" Nov 1 00:25:36.581204 systemd[1]: Created slice kubepods-besteffort-podfeae44ce_a18b_4660_b0ce_4ad1d36cfbb3.slice - libcontainer container kubepods-besteffort-podfeae44ce_a18b_4660_b0ce_4ad1d36cfbb3.slice. Nov 1 00:25:36.587676 kubelet[1763]: I1101 00:25:36.587536 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6kvhp\" (UniqueName: \"kubernetes.io/projected/feae44ce-a18b-4660-b0ce-4ad1d36cfbb3-kube-api-access-6kvhp\") pod \"nginx-deployment-7fcdb87857-tjkcn\" (UID: \"feae44ce-a18b-4660-b0ce-4ad1d36cfbb3\") " pod="default/nginx-deployment-7fcdb87857-tjkcn" Nov 1 00:25:36.603935 systemd-networkd[1399]: flannel.1: Gained IPv6LL Nov 1 00:25:36.885392 containerd[1481]: time="2025-11-01T00:25:36.885240602Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-tjkcn,Uid:feae44ce-a18b-4660-b0ce-4ad1d36cfbb3,Namespace:default,Attempt:0,}" Nov 1 00:25:36.893942 kubelet[1763]: E1101 00:25:36.893807 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:36.922930 systemd-networkd[1399]: cni0: Link UP Nov 1 00:25:36.938262 systemd-networkd[1399]: vethda51b132: Link UP Nov 1 00:25:36.939025 kernel: cni0: port 1(vethda51b132) entered blocking state Nov 1 00:25:36.939085 kernel: cni0: port 1(vethda51b132) entered disabled state Nov 1 00:25:36.943404 kernel: vethda51b132: entered allmulticast mode Nov 1 00:25:36.952299 kernel: vethda51b132: entered promiscuous mode Nov 1 00:25:36.952362 kernel: cni0: port 1(vethda51b132) entered blocking state Nov 1 00:25:36.952388 kernel: cni0: port 1(vethda51b132) entered forwarding state Nov 1 00:25:36.957151 kernel: cni0: port 1(vethda51b132) entered disabled state Nov 1 00:25:36.973951 kernel: cni0: port 1(vethda51b132) entered blocking state Nov 1 00:25:36.974034 kernel: cni0: port 1(vethda51b132) entered forwarding state Nov 1 00:25:36.974528 systemd-networkd[1399]: vethda51b132: Gained carrier Nov 1 00:25:36.977033 systemd-networkd[1399]: cni0: Gained carrier Nov 1 00:25:36.980264 containerd[1481]: 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)(0xc000084950), "name":"cbr0", "type":"bridge"} Nov 1 00:25:36.980264 containerd[1481]: delegateAdd: netconf sent to delegate plugin: Nov 1 00:25:37.008989 containerd[1481]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-01T00:25:37.008654896Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 1 00:25:37.008989 containerd[1481]: time="2025-11-01T00:25:37.008724737Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 1 00:25:37.008989 containerd[1481]: time="2025-11-01T00:25:37.008744364Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:37.008989 containerd[1481]: time="2025-11-01T00:25:37.008837919Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:37.042862 systemd[1]: Started cri-containerd-18af17ade4dfaf3164a8214325e09c3ce06cfd02318da2af6997142b32aec1ed.scope - libcontainer container 18af17ade4dfaf3164a8214325e09c3ce06cfd02318da2af6997142b32aec1ed. Nov 1 00:25:37.099911 containerd[1481]: time="2025-11-01T00:25:37.099856028Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-tjkcn,Uid:feae44ce-a18b-4660-b0ce-4ad1d36cfbb3,Namespace:default,Attempt:0,} returns sandbox id \"18af17ade4dfaf3164a8214325e09c3ce06cfd02318da2af6997142b32aec1ed\"" Nov 1 00:25:37.102944 containerd[1481]: time="2025-11-01T00:25:37.102861463Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 1 00:25:37.701040 systemd[1]: run-containerd-runc-k8s.io-18af17ade4dfaf3164a8214325e09c3ce06cfd02318da2af6997142b32aec1ed-runc.CKwzus.mount: Deactivated successfully. Nov 1 00:25:37.894877 kubelet[1763]: E1101 00:25:37.894783 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:38.523922 systemd-networkd[1399]: cni0: Gained IPv6LL Nov 1 00:25:38.843981 systemd-networkd[1399]: vethda51b132: Gained IPv6LL Nov 1 00:25:38.895172 kubelet[1763]: E1101 00:25:38.895120 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:39.441689 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3998099295.mount: Deactivated successfully. Nov 1 00:25:39.896128 kubelet[1763]: E1101 00:25:39.896069 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:40.308723 containerd[1481]: time="2025-11-01T00:25:40.308437534Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:40.309688 containerd[1481]: time="2025-11-01T00:25:40.309461023Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73311946" Nov 1 00:25:40.311664 containerd[1481]: time="2025-11-01T00:25:40.310587516Z" level=info msg="ImageCreate event name:\"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:40.313432 containerd[1481]: time="2025-11-01T00:25:40.313391334Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:40.314367 containerd[1481]: time="2025-11-01T00:25:40.314267376Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"73311824\" in 3.211332194s" Nov 1 00:25:40.314367 containerd[1481]: time="2025-11-01T00:25:40.314293104Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\"" Nov 1 00:25:40.317756 containerd[1481]: time="2025-11-01T00:25:40.317718427Z" level=info msg="CreateContainer within sandbox \"18af17ade4dfaf3164a8214325e09c3ce06cfd02318da2af6997142b32aec1ed\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Nov 1 00:25:40.328362 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1557902139.mount: Deactivated successfully. Nov 1 00:25:40.335241 containerd[1481]: time="2025-11-01T00:25:40.335198785Z" level=info msg="CreateContainer within sandbox \"18af17ade4dfaf3164a8214325e09c3ce06cfd02318da2af6997142b32aec1ed\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"dbcb898f2595b84d95a000fb3896ec23fbdc3ee30dbe450e3994c3801f3d301f\"" Nov 1 00:25:40.335889 containerd[1481]: time="2025-11-01T00:25:40.335848323Z" level=info msg="StartContainer for \"dbcb898f2595b84d95a000fb3896ec23fbdc3ee30dbe450e3994c3801f3d301f\"" Nov 1 00:25:40.364797 systemd[1]: Started cri-containerd-dbcb898f2595b84d95a000fb3896ec23fbdc3ee30dbe450e3994c3801f3d301f.scope - libcontainer container dbcb898f2595b84d95a000fb3896ec23fbdc3ee30dbe450e3994c3801f3d301f. Nov 1 00:25:40.386017 containerd[1481]: time="2025-11-01T00:25:40.385979844Z" level=info msg="StartContainer for \"dbcb898f2595b84d95a000fb3896ec23fbdc3ee30dbe450e3994c3801f3d301f\" returns successfully" Nov 1 00:25:40.896752 kubelet[1763]: E1101 00:25:40.896692 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:41.897155 kubelet[1763]: E1101 00:25:41.897101 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:42.898170 kubelet[1763]: E1101 00:25:42.898076 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:43.899321 kubelet[1763]: E1101 00:25:43.899235 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:44.881721 kubelet[1763]: E1101 00:25:44.881639 1763 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:44.900351 kubelet[1763]: E1101 00:25:44.900271 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:45.901187 kubelet[1763]: E1101 00:25:45.901080 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:46.901901 kubelet[1763]: E1101 00:25:46.901804 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:47.902754 kubelet[1763]: E1101 00:25:47.902650 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:48.903851 kubelet[1763]: E1101 00:25:48.903735 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:49.904849 kubelet[1763]: E1101 00:25:49.904738 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:50.567505 kubelet[1763]: I1101 00:25:50.567439 1763 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-tjkcn" podStartSLOduration=11.353904433 podStartE2EDuration="14.567414792s" podCreationTimestamp="2025-11-01 00:25:36 +0000 UTC" firstStartedPulling="2025-11-01 00:25:37.101858772 +0000 UTC m=+12.791484437" lastFinishedPulling="2025-11-01 00:25:40.315369182 +0000 UTC m=+16.004994796" observedRunningTime="2025-11-01 00:25:41.041410293 +0000 UTC m=+16.731035917" watchObservedRunningTime="2025-11-01 00:25:50.567414792 +0000 UTC m=+26.257040416" Nov 1 00:25:50.575528 systemd[1]: Created slice kubepods-besteffort-pod9c63b673_d456_4c37_88fb_1d339d76e4c9.slice - libcontainer container kubepods-besteffort-pod9c63b673_d456_4c37_88fb_1d339d76e4c9.slice. Nov 1 00:25:50.680537 kubelet[1763]: I1101 00:25:50.680428 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-j48r6\" (UniqueName: \"kubernetes.io/projected/9c63b673-d456-4c37-88fb-1d339d76e4c9-kube-api-access-j48r6\") pod \"nfs-server-provisioner-0\" (UID: \"9c63b673-d456-4c37-88fb-1d339d76e4c9\") " pod="default/nfs-server-provisioner-0" Nov 1 00:25:50.680537 kubelet[1763]: I1101 00:25:50.680538 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/9c63b673-d456-4c37-88fb-1d339d76e4c9-data\") pod \"nfs-server-provisioner-0\" (UID: \"9c63b673-d456-4c37-88fb-1d339d76e4c9\") " pod="default/nfs-server-provisioner-0" Nov 1 00:25:50.878804 containerd[1481]: time="2025-11-01T00:25:50.878345037Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9c63b673-d456-4c37-88fb-1d339d76e4c9,Namespace:default,Attempt:0,}" Nov 1 00:25:50.905317 kubelet[1763]: E1101 00:25:50.905225 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:50.915371 systemd-networkd[1399]: vethd5a662ce: Link UP Nov 1 00:25:50.920652 kernel: cni0: port 2(vethd5a662ce) entered blocking state Nov 1 00:25:50.920769 kernel: cni0: port 2(vethd5a662ce) entered disabled state Nov 1 00:25:50.920804 kernel: vethd5a662ce: entered allmulticast mode Nov 1 00:25:50.924982 kernel: vethd5a662ce: entered promiscuous mode Nov 1 00:25:50.941782 kernel: cni0: port 2(vethd5a662ce) entered blocking state Nov 1 00:25:50.941859 kernel: cni0: port 2(vethd5a662ce) entered forwarding state Nov 1 00:25:50.944461 systemd-networkd[1399]: vethd5a662ce: Gained carrier Nov 1 00:25:50.948180 containerd[1481]: 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)(0xc0001047f0), "name":"cbr0", "type":"bridge"} Nov 1 00:25:50.948180 containerd[1481]: delegateAdd: netconf sent to delegate plugin: Nov 1 00:25:50.990651 containerd[1481]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-01T00:25:50.987329674Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 1 00:25:50.990651 containerd[1481]: time="2025-11-01T00:25:50.987398874Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 1 00:25:50.990651 containerd[1481]: time="2025-11-01T00:25:50.987418040Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:50.990651 containerd[1481]: time="2025-11-01T00:25:50.987518558Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:25:51.018302 systemd[1]: run-containerd-runc-k8s.io-ebbd3f1c5126ca5b8205b6209f0a716df645878b1e1015e2c9c1834f19ad047a-runc.GlCEey.mount: Deactivated successfully. Nov 1 00:25:51.028036 systemd[1]: Started cri-containerd-ebbd3f1c5126ca5b8205b6209f0a716df645878b1e1015e2c9c1834f19ad047a.scope - libcontainer container ebbd3f1c5126ca5b8205b6209f0a716df645878b1e1015e2c9c1834f19ad047a. Nov 1 00:25:51.087807 containerd[1481]: time="2025-11-01T00:25:51.087764400Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9c63b673-d456-4c37-88fb-1d339d76e4c9,Namespace:default,Attempt:0,} returns sandbox id \"ebbd3f1c5126ca5b8205b6209f0a716df645878b1e1015e2c9c1834f19ad047a\"" Nov 1 00:25:51.090452 containerd[1481]: time="2025-11-01T00:25:51.090408928Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Nov 1 00:25:51.905962 kubelet[1763]: E1101 00:25:51.905916 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:52.156005 systemd-networkd[1399]: vethd5a662ce: Gained IPv6LL Nov 1 00:25:52.784741 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3318279417.mount: Deactivated successfully. Nov 1 00:25:52.906921 kubelet[1763]: E1101 00:25:52.906694 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:53.907459 kubelet[1763]: E1101 00:25:53.907401 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:53.970526 containerd[1481]: time="2025-11-01T00:25:53.970476699Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:53.971475 containerd[1481]: time="2025-11-01T00:25:53.971295524Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039476" Nov 1 00:25:53.973895 containerd[1481]: time="2025-11-01T00:25:53.972455029Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:53.977438 containerd[1481]: time="2025-11-01T00:25:53.977239630Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:25:53.977810 containerd[1481]: time="2025-11-01T00:25:53.977791285Z" 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 2.88733647s" Nov 1 00:25:53.978440 containerd[1481]: time="2025-11-01T00:25:53.978407751Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Nov 1 00:25:53.981606 containerd[1481]: time="2025-11-01T00:25:53.981579367Z" level=info msg="CreateContainer within sandbox \"ebbd3f1c5126ca5b8205b6209f0a716df645878b1e1015e2c9c1834f19ad047a\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Nov 1 00:25:54.000203 containerd[1481]: time="2025-11-01T00:25:54.000168284Z" level=info msg="CreateContainer within sandbox \"ebbd3f1c5126ca5b8205b6209f0a716df645878b1e1015e2c9c1834f19ad047a\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"76100b2d6b15a79cf5c1d4d147a399fcebbb20fad6204015e0c64ef73c46ae92\"" Nov 1 00:25:54.000758 containerd[1481]: time="2025-11-01T00:25:54.000737491Z" level=info msg="StartContainer for \"76100b2d6b15a79cf5c1d4d147a399fcebbb20fad6204015e0c64ef73c46ae92\"" Nov 1 00:25:54.057748 systemd[1]: Started cri-containerd-76100b2d6b15a79cf5c1d4d147a399fcebbb20fad6204015e0c64ef73c46ae92.scope - libcontainer container 76100b2d6b15a79cf5c1d4d147a399fcebbb20fad6204015e0c64ef73c46ae92. Nov 1 00:25:54.081100 containerd[1481]: time="2025-11-01T00:25:54.080499954Z" level=info msg="StartContainer for \"76100b2d6b15a79cf5c1d4d147a399fcebbb20fad6204015e0c64ef73c46ae92\" returns successfully" Nov 1 00:25:54.907676 kubelet[1763]: E1101 00:25:54.907565 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:55.080425 kubelet[1763]: I1101 00:25:55.080337 1763 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.190814581 podStartE2EDuration="5.08031575s" podCreationTimestamp="2025-11-01 00:25:50 +0000 UTC" firstStartedPulling="2025-11-01 00:25:51.089788285 +0000 UTC m=+26.779413929" lastFinishedPulling="2025-11-01 00:25:53.979289484 +0000 UTC m=+29.668915098" observedRunningTime="2025-11-01 00:25:55.080224278 +0000 UTC m=+30.769849933" watchObservedRunningTime="2025-11-01 00:25:55.08031575 +0000 UTC m=+30.769941405" Nov 1 00:25:55.908268 kubelet[1763]: E1101 00:25:55.908159 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:56.439288 update_engine[1468]: I20251101 00:25:56.439146 1468 update_attempter.cc:509] Updating boot flags... Nov 1 00:25:56.509794 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2759) Nov 1 00:25:56.583688 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2759) Nov 1 00:25:56.641653 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 34 scanned by (udev-worker) (2759) Nov 1 00:25:56.908594 kubelet[1763]: E1101 00:25:56.908499 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:57.909700 kubelet[1763]: E1101 00:25:57.909644 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:58.910743 kubelet[1763]: E1101 00:25:58.910685 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:59.338680 systemd[1]: Created slice kubepods-besteffort-pod5701e1fc_cf8d_4276_810b_4154c23504ac.slice - libcontainer container kubepods-besteffort-pod5701e1fc_cf8d_4276_810b_4154c23504ac.slice. Nov 1 00:25:59.439533 kubelet[1763]: I1101 00:25:59.439412 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-b917f83f-720d-4c24-aca8-273c163f9f59\" (UniqueName: \"kubernetes.io/nfs/5701e1fc-cf8d-4276-810b-4154c23504ac-pvc-b917f83f-720d-4c24-aca8-273c163f9f59\") pod \"test-pod-1\" (UID: \"5701e1fc-cf8d-4276-810b-4154c23504ac\") " pod="default/test-pod-1" Nov 1 00:25:59.439533 kubelet[1763]: I1101 00:25:59.439501 1763 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rr7ds\" (UniqueName: \"kubernetes.io/projected/5701e1fc-cf8d-4276-810b-4154c23504ac-kube-api-access-rr7ds\") pod \"test-pod-1\" (UID: \"5701e1fc-cf8d-4276-810b-4154c23504ac\") " pod="default/test-pod-1" Nov 1 00:25:59.581096 kernel: FS-Cache: Loaded Nov 1 00:25:59.675361 kernel: RPC: Registered named UNIX socket transport module. Nov 1 00:25:59.675502 kernel: RPC: Registered udp transport module. Nov 1 00:25:59.675534 kernel: RPC: Registered tcp transport module. Nov 1 00:25:59.678670 kernel: RPC: Registered tcp-with-tls transport module. Nov 1 00:25:59.678839 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 1 00:25:59.907743 kernel: NFS: Registering the id_resolver key type Nov 1 00:25:59.907843 kernel: Key type id_resolver registered Nov 1 00:25:59.917072 kernel: Key type id_legacy registered Nov 1 00:25:59.917156 kubelet[1763]: E1101 00:25:59.917131 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:25:59.937508 nfsidmap[2792]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 1 00:25:59.939874 nfsidmap[2793]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Nov 1 00:26:00.245751 containerd[1481]: time="2025-11-01T00:26:00.245543068Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:5701e1fc-cf8d-4276-810b-4154c23504ac,Namespace:default,Attempt:0,}" Nov 1 00:26:00.299666 kernel: cni0: port 3(vethd90dc766) entered blocking state Nov 1 00:26:00.299797 kernel: cni0: port 3(vethd90dc766) entered disabled state Nov 1 00:26:00.300833 systemd-networkd[1399]: vethd90dc766: Link UP Nov 1 00:26:00.305697 kernel: vethd90dc766: entered allmulticast mode Nov 1 00:26:00.310673 kernel: vethd90dc766: entered promiscuous mode Nov 1 00:26:00.323392 kernel: cni0: port 3(vethd90dc766) entered blocking state Nov 1 00:26:00.323503 kernel: cni0: port 3(vethd90dc766) entered forwarding state Nov 1 00:26:00.323986 systemd-networkd[1399]: vethd90dc766: Gained carrier Nov 1 00:26:00.331875 containerd[1481]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Nov 1 00:26:00.331875 containerd[1481]: delegateAdd: netconf sent to delegate plugin: Nov 1 00:26:00.361357 containerd[1481]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-11-01T00:26:00.361026941Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Nov 1 00:26:00.361659 containerd[1481]: time="2025-11-01T00:26:00.361327826Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Nov 1 00:26:00.361947 containerd[1481]: time="2025-11-01T00:26:00.361781987Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:26:00.363045 containerd[1481]: time="2025-11-01T00:26:00.362942974Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Nov 1 00:26:00.387759 systemd[1]: Started cri-containerd-a2bf8f61197d3ed7784f9e175f8d09f14392f7ce8843c5592157e98e37ed9b8b.scope - libcontainer container a2bf8f61197d3ed7784f9e175f8d09f14392f7ce8843c5592157e98e37ed9b8b. Nov 1 00:26:00.439015 containerd[1481]: time="2025-11-01T00:26:00.438934957Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:5701e1fc-cf8d-4276-810b-4154c23504ac,Namespace:default,Attempt:0,} returns sandbox id \"a2bf8f61197d3ed7784f9e175f8d09f14392f7ce8843c5592157e98e37ed9b8b\"" Nov 1 00:26:00.441240 containerd[1481]: time="2025-11-01T00:26:00.441195035Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Nov 1 00:26:00.898277 containerd[1481]: time="2025-11-01T00:26:00.898208251Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Nov 1 00:26:00.899636 containerd[1481]: time="2025-11-01T00:26:00.899127094Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Nov 1 00:26:00.901640 containerd[1481]: time="2025-11-01T00:26:00.901579883Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"73311824\" in 460.29485ms" Nov 1 00:26:00.901640 containerd[1481]: time="2025-11-01T00:26:00.901627453Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:8d14817f00613fe76ef7459f977ad93e7b71a3948346b7ac4d50e35f3dd518e9\"" Nov 1 00:26:00.905001 containerd[1481]: time="2025-11-01T00:26:00.904908365Z" level=info msg="CreateContainer within sandbox \"a2bf8f61197d3ed7784f9e175f8d09f14392f7ce8843c5592157e98e37ed9b8b\" for container &ContainerMetadata{Name:test,Attempt:0,}" Nov 1 00:26:00.917757 kubelet[1763]: E1101 00:26:00.917711 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:00.923885 containerd[1481]: time="2025-11-01T00:26:00.923847226Z" level=info msg="CreateContainer within sandbox \"a2bf8f61197d3ed7784f9e175f8d09f14392f7ce8843c5592157e98e37ed9b8b\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"5d9ac925eeb3acda537159a0becbee51218c5cb524f04ea053caab61dee669e8\"" Nov 1 00:26:00.924389 containerd[1481]: time="2025-11-01T00:26:00.924295167Z" level=info msg="StartContainer for \"5d9ac925eeb3acda537159a0becbee51218c5cb524f04ea053caab61dee669e8\"" Nov 1 00:26:00.952770 systemd[1]: Started cri-containerd-5d9ac925eeb3acda537159a0becbee51218c5cb524f04ea053caab61dee669e8.scope - libcontainer container 5d9ac925eeb3acda537159a0becbee51218c5cb524f04ea053caab61dee669e8. Nov 1 00:26:00.974012 containerd[1481]: time="2025-11-01T00:26:00.973970773Z" level=info msg="StartContainer for \"5d9ac925eeb3acda537159a0becbee51218c5cb524f04ea053caab61dee669e8\" returns successfully" Nov 1 00:26:01.087250 kubelet[1763]: I1101 00:26:01.087198 1763 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=8.625111597 podStartE2EDuration="9.08718422s" podCreationTimestamp="2025-11-01 00:25:52 +0000 UTC" firstStartedPulling="2025-11-01 00:26:00.440142791 +0000 UTC m=+36.129768426" lastFinishedPulling="2025-11-01 00:26:00.902215425 +0000 UTC m=+36.591841049" observedRunningTime="2025-11-01 00:26:01.086881332 +0000 UTC m=+36.776506956" watchObservedRunningTime="2025-11-01 00:26:01.08718422 +0000 UTC m=+36.776809834" Nov 1 00:26:01.919011 kubelet[1763]: E1101 00:26:01.918869 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:01.947961 systemd-networkd[1399]: vethd90dc766: Gained IPv6LL Nov 1 00:26:02.920071 kubelet[1763]: E1101 00:26:02.919985 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:03.921306 kubelet[1763]: E1101 00:26:03.921197 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:04.881589 kubelet[1763]: E1101 00:26:04.881501 1763 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:04.922278 kubelet[1763]: E1101 00:26:04.922221 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:05.923166 kubelet[1763]: E1101 00:26:05.923084 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:06.924070 kubelet[1763]: E1101 00:26:06.923974 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:07.924885 kubelet[1763]: E1101 00:26:07.924817 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:08.925919 kubelet[1763]: E1101 00:26:08.925797 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:09.927047 kubelet[1763]: E1101 00:26:09.926971 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:10.927820 kubelet[1763]: E1101 00:26:10.927721 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:11.928462 kubelet[1763]: E1101 00:26:11.928371 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:12.929711 kubelet[1763]: E1101 00:26:12.929594 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:13.930892 kubelet[1763]: E1101 00:26:13.930759 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:14.930990 kubelet[1763]: E1101 00:26:14.930905 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:15.931697 kubelet[1763]: E1101 00:26:15.931633 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:16.932771 kubelet[1763]: E1101 00:26:16.932699 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:17.933395 kubelet[1763]: E1101 00:26:17.933306 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:18.933734 kubelet[1763]: E1101 00:26:18.933687 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:19.934603 kubelet[1763]: E1101 00:26:19.934522 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:20.935664 kubelet[1763]: E1101 00:26:20.935473 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:21.935908 kubelet[1763]: E1101 00:26:21.935863 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Nov 1 00:26:22.937034 kubelet[1763]: E1101 00:26:22.936944 1763 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"