Sep 5 00:04:39.934113 kernel: Linux version 6.6.156-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Fri Sep 4 22:21:59 -00 2026 Sep 5 00:04:39.934131 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=9215c603110941002c0250d1c5b0629b89b2cc5fa29e7b45d5a5566f7e6b6ee1 Sep 5 00:04:39.934137 kernel: BIOS-provided physical RAM map: Sep 5 00:04:39.934145 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 5 00:04:39.934150 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ed3efff] usable Sep 5 00:04:39.934154 kernel: BIOS-e820: [mem 0x000000007ed3f000-0x000000007edfffff] reserved Sep 5 00:04:39.934160 kernel: BIOS-e820: [mem 0x000000007ee00000-0x000000007f8ecfff] usable Sep 5 00:04:39.934164 kernel: BIOS-e820: [mem 0x000000007f8ed000-0x000000007f9ecfff] reserved Sep 5 00:04:39.934169 kernel: BIOS-e820: [mem 0x000000007f9ed000-0x000000007faecfff] type 20 Sep 5 00:04:39.934173 kernel: BIOS-e820: [mem 0x000000007faed000-0x000000007fb6cfff] reserved Sep 5 00:04:39.934178 kernel: BIOS-e820: [mem 0x000000007fb6d000-0x000000007fb7efff] ACPI data Sep 5 00:04:39.934182 kernel: BIOS-e820: [mem 0x000000007fb7f000-0x000000007fbfefff] ACPI NVS Sep 5 00:04:39.934189 kernel: BIOS-e820: [mem 0x000000007fbff000-0x000000007ff7bfff] usable Sep 5 00:04:39.934194 kernel: BIOS-e820: [mem 0x000000007ff7c000-0x000000007fffffff] reserved Sep 5 00:04:39.934199 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 5 00:04:39.934204 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 5 00:04:39.934209 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Sep 5 00:04:39.934216 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000179ffffff] usable Sep 5 00:04:39.934220 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 5 00:04:39.934225 kernel: NX (Execute Disable) protection: active Sep 5 00:04:39.934230 kernel: APIC: Static calls initialized Sep 5 00:04:39.934234 kernel: efi: EFI v2.7 by Ubuntu distribution of EDK II Sep 5 00:04:39.934239 kernel: efi: SMBIOS=0x7f988000 SMBIOS 3.0=0x7f986000 ACPI=0x7fb7e000 ACPI 2.0=0x7fb7e014 MEMATTR=0x7e845198 Sep 5 00:04:39.934244 kernel: efi: Remove mem135: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map Sep 5 00:04:39.934249 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved Sep 5 00:04:39.934253 kernel: SMBIOS 3.0.0 present. Sep 5 00:04:39.934258 kernel: DMI: Hetzner vServer/Standard PC (Q35 + ICH9, 2009), BIOS 20171111 11/11/2017 Sep 5 00:04:39.934263 kernel: Hypervisor detected: KVM Sep 5 00:04:39.934270 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 5 00:04:39.934275 kernel: kvm-clock: using sched offset of 12792722608 cycles Sep 5 00:04:39.934280 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 5 00:04:39.934285 kernel: tsc: Detected 2399.998 MHz processor Sep 5 00:04:39.934290 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 5 00:04:39.934294 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 5 00:04:39.934299 kernel: last_pfn = 0x17a000 max_arch_pfn = 0x10000000000 Sep 5 00:04:39.934304 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 5 00:04:39.934309 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 5 00:04:39.934313 kernel: last_pfn = 0x7ff7c max_arch_pfn = 0x10000000000 Sep 5 00:04:39.934321 kernel: Using GB pages for direct mapping Sep 5 00:04:39.934325 kernel: Secure boot disabled Sep 5 00:04:39.934333 kernel: ACPI: Early table checksum verification disabled Sep 5 00:04:39.934338 kernel: ACPI: RSDP 0x000000007FB7E014 000024 (v02 BOCHS ) Sep 5 00:04:39.934343 kernel: ACPI: XSDT 0x000000007FB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Sep 5 00:04:39.934348 kernel: ACPI: FACP 0x000000007FB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:04:39.934356 kernel: ACPI: DSDT 0x000000007FB7A000 002443 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:04:39.934360 kernel: ACPI: FACS 0x000000007FBDD000 000040 Sep 5 00:04:39.934365 kernel: ACPI: APIC 0x000000007FB78000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:04:39.934370 kernel: ACPI: HPET 0x000000007FB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:04:39.934375 kernel: ACPI: MCFG 0x000000007FB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:04:39.934380 kernel: ACPI: WAET 0x000000007FB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 00:04:39.934385 kernel: ACPI: BGRT 0x000000007FB74000 000038 (v01 INTEL EDK2 00000002 01000013) Sep 5 00:04:39.934390 kernel: ACPI: Reserving FACP table memory at [mem 0x7fb79000-0x7fb790f3] Sep 5 00:04:39.934398 kernel: ACPI: Reserving DSDT table memory at [mem 0x7fb7a000-0x7fb7c442] Sep 5 00:04:39.934403 kernel: ACPI: Reserving FACS table memory at [mem 0x7fbdd000-0x7fbdd03f] Sep 5 00:04:39.934408 kernel: ACPI: Reserving APIC table memory at [mem 0x7fb78000-0x7fb7807f] Sep 5 00:04:39.934412 kernel: ACPI: Reserving HPET table memory at [mem 0x7fb77000-0x7fb77037] Sep 5 00:04:39.934417 kernel: ACPI: Reserving MCFG table memory at [mem 0x7fb76000-0x7fb7603b] Sep 5 00:04:39.934422 kernel: ACPI: Reserving WAET table memory at [mem 0x7fb75000-0x7fb75027] Sep 5 00:04:39.934427 kernel: ACPI: Reserving BGRT table memory at [mem 0x7fb74000-0x7fb74037] Sep 5 00:04:39.934433 kernel: No NUMA configuration found Sep 5 00:04:39.934438 kernel: Faking a node at [mem 0x0000000000000000-0x0000000179ffffff] Sep 5 00:04:39.934446 kernel: NODE_DATA(0) allocated [mem 0x179ffa000-0x179ffffff] Sep 5 00:04:39.934464 kernel: Zone ranges: Sep 5 00:04:39.934469 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 5 00:04:39.934474 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 5 00:04:39.934479 kernel: Normal [mem 0x0000000100000000-0x0000000179ffffff] Sep 5 00:04:39.934484 kernel: Movable zone start for each node Sep 5 00:04:39.934489 kernel: Early memory node ranges Sep 5 00:04:39.934494 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 5 00:04:39.934499 kernel: node 0: [mem 0x0000000000100000-0x000000007ed3efff] Sep 5 00:04:39.934507 kernel: node 0: [mem 0x000000007ee00000-0x000000007f8ecfff] Sep 5 00:04:39.934512 kernel: node 0: [mem 0x000000007fbff000-0x000000007ff7bfff] Sep 5 00:04:39.934517 kernel: node 0: [mem 0x0000000100000000-0x0000000179ffffff] Sep 5 00:04:39.934522 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000000179ffffff] Sep 5 00:04:39.934527 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 5 00:04:39.934532 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 5 00:04:39.934537 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges Sep 5 00:04:39.934542 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Sep 5 00:04:39.934547 kernel: On node 0, zone Normal: 132 pages in unavailable ranges Sep 5 00:04:39.934552 kernel: On node 0, zone Normal: 24576 pages in unavailable ranges Sep 5 00:04:39.934559 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 5 00:04:39.934564 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 5 00:04:39.934569 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 5 00:04:39.934574 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 5 00:04:39.934579 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 5 00:04:39.934584 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 5 00:04:39.934589 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 5 00:04:39.934594 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 5 00:04:39.934599 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 5 00:04:39.934606 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 5 00:04:39.934611 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Sep 5 00:04:39.934616 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 5 00:04:39.934621 kernel: [mem 0x80000000-0xdfffffff] available for PCI devices Sep 5 00:04:39.934626 kernel: Booting paravirtualized kernel on KVM Sep 5 00:04:39.934631 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 5 00:04:39.934636 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Sep 5 00:04:39.934641 kernel: percpu: Embedded 57 pages/cpu s196392 r8192 d28888 u1048576 Sep 5 00:04:39.934646 kernel: pcpu-alloc: s196392 r8192 d28888 u1048576 alloc=1*2097152 Sep 5 00:04:39.934653 kernel: pcpu-alloc: [0] 0 1 Sep 5 00:04:39.934658 kernel: kvm-guest: PV spinlocks disabled, no host support Sep 5 00:04:39.934664 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=9215c603110941002c0250d1c5b0629b89b2cc5fa29e7b45d5a5566f7e6b6ee1 Sep 5 00:04:39.934669 kernel: random: crng init done Sep 5 00:04:39.934674 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 5 00:04:39.934679 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 5 00:04:39.934684 kernel: Fallback order for Node 0: 0 Sep 5 00:04:39.934689 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1004632 Sep 5 00:04:39.934696 kernel: Policy zone: Normal Sep 5 00:04:39.934701 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 5 00:04:39.934706 kernel: software IO TLB: area num 2. Sep 5 00:04:39.934711 kernel: Memory: 3825788K/4091168K available (14336K kernel code, 2288K rwdata, 22772K rodata, 42920K init, 2236K bss, 265176K reserved, 0K cma-reserved) Sep 5 00:04:39.934716 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 5 00:04:39.934721 kernel: ftrace: allocating 37502 entries in 148 pages Sep 5 00:04:39.934726 kernel: ftrace: allocated 148 pages with 3 groups Sep 5 00:04:39.934731 kernel: Dynamic Preempt: voluntary Sep 5 00:04:39.934736 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 5 00:04:39.934744 kernel: rcu: RCU event tracing is enabled. Sep 5 00:04:39.934749 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 5 00:04:39.934754 kernel: Trampoline variant of Tasks RCU enabled. Sep 5 00:04:39.934766 kernel: Rude variant of Tasks RCU enabled. Sep 5 00:04:39.934774 kernel: Tracing variant of Tasks RCU enabled. Sep 5 00:04:39.934779 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 5 00:04:39.934784 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 5 00:04:39.934789 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Sep 5 00:04:39.934794 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 5 00:04:39.934799 kernel: Console: colour dummy device 80x25 Sep 5 00:04:39.934804 kernel: printk: console [tty0] enabled Sep 5 00:04:39.934810 kernel: printk: console [ttyS0] enabled Sep 5 00:04:39.934817 kernel: ACPI: Core revision 20230628 Sep 5 00:04:39.934822 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 5 00:04:39.934828 kernel: APIC: Switch to symmetric I/O mode setup Sep 5 00:04:39.934833 kernel: x2apic enabled Sep 5 00:04:39.934838 kernel: APIC: Switched APIC routing to: physical x2apic Sep 5 00:04:39.934846 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 5 00:04:39.934851 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Sep 5 00:04:39.934856 kernel: Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) Sep 5 00:04:39.934861 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 5 00:04:39.934866 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 5 00:04:39.934872 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 5 00:04:39.934877 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 5 00:04:39.934882 kernel: Spectre V2 : Mitigation: Enhanced / Automatic IBRS Sep 5 00:04:39.934887 kernel: Spectre V2 : Enabling IBPB for BPF Sep 5 00:04:39.934895 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 5 00:04:39.934900 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 5 00:04:39.934905 kernel: active return thunk: srso_alias_return_thunk Sep 5 00:04:39.934910 kernel: Speculative Return Stack Overflow: Mitigation: Safe RET Sep 5 00:04:39.934915 kernel: Transient Scheduler Attacks: Forcing mitigation on in a VM Sep 5 00:04:39.934929 kernel: Transient Scheduler Attacks: Vulnerable: Clear CPU buffers attempted, no microcode Sep 5 00:04:39.934934 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 5 00:04:39.934940 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 5 00:04:39.934945 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 5 00:04:39.934953 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Sep 5 00:04:39.934958 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Sep 5 00:04:39.934963 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Sep 5 00:04:39.934968 kernel: x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' Sep 5 00:04:39.934980 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 5 00:04:39.934986 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Sep 5 00:04:39.934991 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Sep 5 00:04:39.934996 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Sep 5 00:04:39.935004 kernel: x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 Sep 5 00:04:39.935009 kernel: x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. Sep 5 00:04:39.935014 kernel: Freeing SMP alternatives memory: 32K Sep 5 00:04:39.935019 kernel: pid_max: default: 32768 minimum: 301 Sep 5 00:04:39.935025 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 5 00:04:39.935030 kernel: landlock: Up and running. Sep 5 00:04:39.935035 kernel: SELinux: Initializing. Sep 5 00:04:39.935040 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 00:04:39.935046 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 00:04:39.935053 kernel: smpboot: CPU0: AMD EPYC-Genoa Processor (family: 0x19, model: 0x11, stepping: 0x0) Sep 5 00:04:39.935058 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 5 00:04:39.935064 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 5 00:04:39.935069 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 5 00:04:39.935074 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 5 00:04:39.935079 kernel: ... version: 0 Sep 5 00:04:39.935084 kernel: ... bit width: 48 Sep 5 00:04:39.935090 kernel: ... generic registers: 6 Sep 5 00:04:39.935095 kernel: ... value mask: 0000ffffffffffff Sep 5 00:04:39.935102 kernel: ... max period: 00007fffffffffff Sep 5 00:04:39.935107 kernel: ... fixed-purpose events: 0 Sep 5 00:04:39.935113 kernel: ... event mask: 000000000000003f Sep 5 00:04:39.935118 kernel: signal: max sigframe size: 3376 Sep 5 00:04:39.935123 kernel: rcu: Hierarchical SRCU implementation. Sep 5 00:04:39.935128 kernel: rcu: Max phase no-delay instances is 400. Sep 5 00:04:39.935133 kernel: smp: Bringing up secondary CPUs ... Sep 5 00:04:39.935139 kernel: smpboot: x86: Booting SMP configuration: Sep 5 00:04:39.935144 kernel: .... node #0, CPUs: #1 Sep 5 00:04:39.935149 kernel: smp: Brought up 1 node, 2 CPUs Sep 5 00:04:39.935156 kernel: smpboot: Max logical packages: 1 Sep 5 00:04:39.935161 kernel: smpboot: Total of 2 processors activated (9599.99 BogoMIPS) Sep 5 00:04:39.935167 kernel: devtmpfs: initialized Sep 5 00:04:39.935172 kernel: x86/mm: Memory block size: 128MB Sep 5 00:04:39.935177 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x7fb7f000-0x7fbfefff] (524288 bytes) Sep 5 00:04:39.935182 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 5 00:04:39.935187 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 5 00:04:39.935193 kernel: pinctrl core: initialized pinctrl subsystem Sep 5 00:04:39.935198 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 5 00:04:39.935205 kernel: audit: initializing netlink subsys (disabled) Sep 5 00:04:39.935211 kernel: audit: type=2000 audit(1788566678.581:1): state=initialized audit_enabled=0 res=1 Sep 5 00:04:39.935216 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 5 00:04:39.935221 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 5 00:04:39.935226 kernel: cpuidle: using governor menu Sep 5 00:04:39.935231 kernel: efi: Freeing EFI boot services memory: 34820K Sep 5 00:04:39.935236 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 5 00:04:39.935242 kernel: dca service started, version 1.12.1 Sep 5 00:04:39.935247 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Sep 5 00:04:39.935254 kernel: PCI: Using configuration type 1 for base access Sep 5 00:04:39.935260 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 5 00:04:39.935265 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 5 00:04:39.935270 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 5 00:04:39.935275 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 5 00:04:39.935280 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 5 00:04:39.935285 kernel: ACPI: Added _OSI(Module Device) Sep 5 00:04:39.935291 kernel: ACPI: Added _OSI(Processor Device) Sep 5 00:04:39.935296 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 5 00:04:39.935303 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 5 00:04:39.935309 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 5 00:04:39.935314 kernel: ACPI: Interpreter enabled Sep 5 00:04:39.935319 kernel: ACPI: PM: (supports S0 S5) Sep 5 00:04:39.935324 kernel: ACPI: Using IOAPIC for interrupt routing Sep 5 00:04:39.935329 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 5 00:04:39.935334 kernel: PCI: Using E820 reservations for host bridge windows Sep 5 00:04:39.935340 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 5 00:04:39.935345 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 5 00:04:39.935524 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 5 00:04:39.935634 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 5 00:04:39.935731 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 5 00:04:39.935737 kernel: PCI host bridge to bus 0000:00 Sep 5 00:04:39.935837 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 5 00:04:39.935926 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 5 00:04:39.936024 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 5 00:04:39.936112 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xdfffffff window] Sep 5 00:04:39.936199 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Sep 5 00:04:39.936286 kernel: pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc7ffffffff window] Sep 5 00:04:39.936373 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 5 00:04:39.936503 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Sep 5 00:04:39.936609 kernel: pci 0000:00:01.0: [1af4:1050] type 00 class 0x030000 Sep 5 00:04:39.936709 kernel: pci 0000:00:01.0: BAR 0 [mem 0x80000000-0x807fffff pref] Sep 5 00:04:39.936805 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc060500000-0xc060503fff 64bit pref] Sep 5 00:04:39.936900 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8138a000-0x8138afff] Sep 5 00:04:39.937005 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Sep 5 00:04:39.937102 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Sep 5 00:04:39.937198 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 5 00:04:39.937305 kernel: pci 0000:00:02.0: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.937401 kernel: pci 0000:00:02.0: BAR 0 [mem 0x81389000-0x81389fff] Sep 5 00:04:39.937523 kernel: pci 0000:00:02.0: PCI bridge to [bus 01] Sep 5 00:04:39.937621 kernel: pci 0000:00:02.0: bridge window [mem 0x81200000-0x812fffff] Sep 5 00:04:39.937718 kernel: pci 0000:00:02.0: bridge window [mem 0xc060000000-0xc0600fffff 64bit pref] Sep 5 00:04:39.937820 kernel: pci 0000:00:02.1: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.937916 kernel: pci 0000:00:02.1: BAR 0 [mem 0x81388000-0x81388fff] Sep 5 00:04:39.938023 kernel: pci 0000:00:02.1: PCI bridge to [bus 02] Sep 5 00:04:39.938119 kernel: pci 0000:00:02.1: bridge window [mem 0x81100000-0x811fffff] Sep 5 00:04:39.938221 kernel: pci 0000:00:02.2: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.938317 kernel: pci 0000:00:02.2: BAR 0 [mem 0x81387000-0x81387fff] Sep 5 00:04:39.938413 kernel: pci 0000:00:02.2: PCI bridge to [bus 03] Sep 5 00:04:39.938524 kernel: pci 0000:00:02.2: bridge window [mem 0x81000000-0x810fffff] Sep 5 00:04:39.938620 kernel: pci 0000:00:02.2: bridge window [mem 0xc060100000-0xc0601fffff 64bit pref] Sep 5 00:04:39.938726 kernel: pci 0000:00:02.3: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.938821 kernel: pci 0000:00:02.3: BAR 0 [mem 0x81386000-0x81386fff] Sep 5 00:04:39.938916 kernel: pci 0000:00:02.3: PCI bridge to [bus 04] Sep 5 00:04:39.939018 kernel: pci 0000:00:02.3: bridge window [mem 0xc060200000-0xc0602fffff 64bit pref] Sep 5 00:04:39.939123 kernel: pci 0000:00:02.4: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.939219 kernel: pci 0000:00:02.4: BAR 0 [mem 0x81385000-0x81385fff] Sep 5 00:04:39.939314 kernel: pci 0000:00:02.4: PCI bridge to [bus 05] Sep 5 00:04:39.939412 kernel: pci 0000:00:02.4: bridge window [mem 0x80f00000-0x80ffffff] Sep 5 00:04:39.939520 kernel: pci 0000:00:02.4: bridge window [mem 0xc060300000-0xc0603fffff 64bit pref] Sep 5 00:04:39.939622 kernel: pci 0000:00:02.5: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.939717 kernel: pci 0000:00:02.5: BAR 0 [mem 0x81384000-0x81384fff] Sep 5 00:04:39.939811 kernel: pci 0000:00:02.5: PCI bridge to [bus 06] Sep 5 00:04:39.939906 kernel: pci 0000:00:02.5: bridge window [mem 0x80e00000-0x80efffff] Sep 5 00:04:39.940010 kernel: pci 0000:00:02.5: bridge window [mem 0xc060400000-0xc0604fffff 64bit pref] Sep 5 00:04:39.940113 kernel: pci 0000:00:02.6: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.940212 kernel: pci 0000:00:02.6: BAR 0 [mem 0x81383000-0x81383fff] Sep 5 00:04:39.940307 kernel: pci 0000:00:02.6: PCI bridge to [bus 07] Sep 5 00:04:39.940402 kernel: pci 0000:00:02.6: bridge window [mem 0x80c00000-0x80dfffff] Sep 5 00:04:39.940549 kernel: pci 0000:00:02.6: bridge window [mem 0xc000000000-0xc01fffffff 64bit pref] Sep 5 00:04:39.940653 kernel: pci 0000:00:02.7: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.940748 kernel: pci 0000:00:02.7: BAR 0 [mem 0x81382000-0x81382fff] Sep 5 00:04:39.940846 kernel: pci 0000:00:02.7: PCI bridge to [bus 08] Sep 5 00:04:39.940941 kernel: pci 0000:00:02.7: bridge window [mem 0x80a00000-0x80bfffff] Sep 5 00:04:39.941042 kernel: pci 0000:00:02.7: bridge window [mem 0xc020000000-0xc03fffffff 64bit pref] Sep 5 00:04:39.941144 kernel: pci 0000:00:03.0: [1b36:000c] type 01 class 0x060400 Sep 5 00:04:39.941238 kernel: pci 0000:00:03.0: BAR 0 [mem 0x81381000-0x81381fff] Sep 5 00:04:39.941332 kernel: pci 0000:00:03.0: PCI bridge to [bus 09] Sep 5 00:04:39.941427 kernel: pci 0000:00:03.0: bridge window [mem 0x80800000-0x809fffff] Sep 5 00:04:39.942599 kernel: pci 0000:00:03.0: bridge window [mem 0xc040000000-0xc05fffffff 64bit pref] Sep 5 00:04:39.942710 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Sep 5 00:04:39.942807 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 5 00:04:39.942907 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Sep 5 00:04:39.943013 kernel: pci 0000:00:1f.2: BAR 4 [io 0x6040-0x605f] Sep 5 00:04:39.943107 kernel: pci 0000:00:1f.2: BAR 5 [mem 0x81380000-0x81380fff] Sep 5 00:04:39.943208 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Sep 5 00:04:39.943307 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6000-0x603f] Sep 5 00:04:39.943414 kernel: pci 0000:01:00.0: [1af4:1041] type 00 class 0x020000 Sep 5 00:04:39.944595 kernel: pci 0000:01:00.0: BAR 1 [mem 0x81200000-0x81200fff] Sep 5 00:04:39.944727 kernel: pci 0000:01:00.0: BAR 4 [mem 0xc060000000-0xc060003fff 64bit pref] Sep 5 00:04:39.944829 kernel: pci 0000:01:00.0: ROM [mem 0xfff80000-0xffffffff pref] Sep 5 00:04:39.944925 kernel: pci 0000:00:02.0: PCI bridge to [bus 01] Sep 5 00:04:39.945047 kernel: pci 0000:02:00.0: [1b36:000d] type 00 class 0x0c0330 Sep 5 00:04:39.945148 kernel: pci 0000:02:00.0: BAR 0 [mem 0x81100000-0x81103fff 64bit] Sep 5 00:04:39.945243 kernel: pci 0000:00:02.1: PCI bridge to [bus 02] Sep 5 00:04:39.945349 kernel: pci 0000:03:00.0: [1af4:1043] type 00 class 0x078000 Sep 5 00:04:39.945447 kernel: pci 0000:03:00.0: BAR 1 [mem 0x81000000-0x81000fff] Sep 5 00:04:39.945560 kernel: pci 0000:03:00.0: BAR 4 [mem 0xc060100000-0xc060103fff 64bit pref] Sep 5 00:04:39.945656 kernel: pci 0000:00:02.2: PCI bridge to [bus 03] Sep 5 00:04:39.945769 kernel: pci 0000:04:00.0: [1af4:1045] type 00 class 0x00ff00 Sep 5 00:04:39.945869 kernel: pci 0000:04:00.0: BAR 4 [mem 0xc060200000-0xc060203fff 64bit pref] Sep 5 00:04:39.945963 kernel: pci 0000:00:02.3: PCI bridge to [bus 04] Sep 5 00:04:39.946079 kernel: pci 0000:05:00.0: [1af4:1044] type 00 class 0x00ff00 Sep 5 00:04:39.946180 kernel: pci 0000:05:00.0: BAR 1 [mem 0x80f00000-0x80f00fff] Sep 5 00:04:39.946279 kernel: pci 0000:05:00.0: BAR 4 [mem 0xc060300000-0xc060303fff 64bit pref] Sep 5 00:04:39.946373 kernel: pci 0000:00:02.4: PCI bridge to [bus 05] Sep 5 00:04:39.948746 kernel: pci 0000:06:00.0: [1af4:1048] type 00 class 0x010000 Sep 5 00:04:39.948861 kernel: pci 0000:06:00.0: BAR 1 [mem 0x80e00000-0x80e00fff] Sep 5 00:04:39.948963 kernel: pci 0000:06:00.0: BAR 4 [mem 0xc060400000-0xc060403fff 64bit pref] Sep 5 00:04:39.949071 kernel: pci 0000:00:02.5: PCI bridge to [bus 06] Sep 5 00:04:39.949078 kernel: acpiphp: Slot [0] registered Sep 5 00:04:39.949186 kernel: pci 0000:07:00.0: [1af4:1041] type 00 class 0x020000 Sep 5 00:04:39.949287 kernel: pci 0000:07:00.0: BAR 1 [mem 0x80c00000-0x80c00fff] Sep 5 00:04:39.949392 kernel: pci 0000:07:00.0: BAR 4 [mem 0xc000000000-0xc000003fff 64bit pref] Sep 5 00:04:39.949508 kernel: pci 0000:07:00.0: ROM [mem 0xfff80000-0xffffffff pref] Sep 5 00:04:39.949604 kernel: pci 0000:00:02.6: PCI bridge to [bus 07] Sep 5 00:04:39.949612 kernel: acpiphp: Slot [0-2] registered Sep 5 00:04:39.949707 kernel: pci 0000:00:02.7: PCI bridge to [bus 08] Sep 5 00:04:39.949713 kernel: acpiphp: Slot [0-3] registered Sep 5 00:04:39.949808 kernel: pci 0000:00:03.0: PCI bridge to [bus 09] Sep 5 00:04:39.949814 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 5 00:04:39.949823 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 5 00:04:39.949829 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 5 00:04:39.949834 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 5 00:04:39.949839 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 5 00:04:39.949845 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 5 00:04:39.949850 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 5 00:04:39.949855 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 5 00:04:39.949860 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 5 00:04:39.949866 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 5 00:04:39.949873 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 5 00:04:39.949878 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 5 00:04:39.949884 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 5 00:04:39.949889 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 5 00:04:39.949894 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 5 00:04:39.949899 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 5 00:04:39.949904 kernel: iommu: Default domain type: Translated Sep 5 00:04:39.949910 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 5 00:04:39.949915 kernel: efivars: Registered efivars operations Sep 5 00:04:39.949923 kernel: PCI: Using ACPI for IRQ routing Sep 5 00:04:39.949928 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 5 00:04:39.949933 kernel: e820: reserve RAM buffer [mem 0x7ed3f000-0x7fffffff] Sep 5 00:04:39.949939 kernel: e820: reserve RAM buffer [mem 0x7f8ed000-0x7fffffff] Sep 5 00:04:39.949944 kernel: e820: reserve RAM buffer [mem 0x7ff7c000-0x7fffffff] Sep 5 00:04:39.949949 kernel: e820: reserve RAM buffer [mem 0x17a000000-0x17bffffff] Sep 5 00:04:39.952651 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 5 00:04:39.952758 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 5 00:04:39.952861 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 5 00:04:39.952885 kernel: vgaarb: loaded Sep 5 00:04:39.952891 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 5 00:04:39.952897 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 5 00:04:39.952903 kernel: clocksource: Switched to clocksource kvm-clock Sep 5 00:04:39.952908 kernel: VFS: Disk quotas dquot_6.6.0 Sep 5 00:04:39.952914 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 5 00:04:39.952920 kernel: pnp: PnP ACPI init Sep 5 00:04:39.953036 kernel: system 00:04: [mem 0xe0000000-0xefffffff window] has been reserved Sep 5 00:04:39.953046 kernel: pnp: PnP ACPI: found 5 devices Sep 5 00:04:39.953052 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 5 00:04:39.953058 kernel: NET: Registered PF_INET protocol family Sep 5 00:04:39.953063 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 5 00:04:39.953069 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 5 00:04:39.953074 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 5 00:04:39.953080 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 5 00:04:39.953085 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 5 00:04:39.953093 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 5 00:04:39.953101 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 00:04:39.953107 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 00:04:39.953112 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 5 00:04:39.953118 kernel: NET: Registered PF_XDP protocol family Sep 5 00:04:39.953221 kernel: pci 0000:01:00.0: ROM [mem 0xfff80000-0xffffffff pref]: can't claim; no compatible bridge window Sep 5 00:04:39.953326 kernel: pci 0000:07:00.0: ROM [mem 0xfff80000-0xffffffff pref]: can't claim; no compatible bridge window Sep 5 00:04:39.953422 kernel: pci 0000:00:02.6: bridge window [io 0x1000-0x0fff] to [bus 07] add_size 1000 Sep 5 00:04:39.953805 kernel: pci 0000:00:02.7: bridge window [io 0x1000-0x0fff] to [bus 08] add_size 1000 Sep 5 00:04:39.953906 kernel: pci 0000:00:03.0: bridge window [io 0x1000-0x0fff] to [bus 09] add_size 1000 Sep 5 00:04:39.954012 kernel: pci 0000:00:02.6: bridge window [io 0x1000-0x1fff]: assigned Sep 5 00:04:39.954108 kernel: pci 0000:00:02.7: bridge window [io 0x2000-0x2fff]: assigned Sep 5 00:04:39.954203 kernel: pci 0000:00:03.0: bridge window [io 0x3000-0x3fff]: assigned Sep 5 00:04:39.954303 kernel: pci 0000:01:00.0: ROM [mem 0x81280000-0x812fffff pref]: assigned Sep 5 00:04:39.954402 kernel: pci 0000:00:02.0: PCI bridge to [bus 01] Sep 5 00:04:39.957020 kernel: pci 0000:00:02.0: bridge window [mem 0x81200000-0x812fffff] Sep 5 00:04:39.957126 kernel: pci 0000:00:02.0: bridge window [mem 0xc060000000-0xc0600fffff 64bit pref] Sep 5 00:04:39.957225 kernel: pci 0000:00:02.1: PCI bridge to [bus 02] Sep 5 00:04:39.957322 kernel: pci 0000:00:02.1: bridge window [mem 0x81100000-0x811fffff] Sep 5 00:04:39.957420 kernel: pci 0000:00:02.2: PCI bridge to [bus 03] Sep 5 00:04:39.957542 kernel: pci 0000:00:02.2: bridge window [mem 0x81000000-0x810fffff] Sep 5 00:04:39.957640 kernel: pci 0000:00:02.2: bridge window [mem 0xc060100000-0xc0601fffff 64bit pref] Sep 5 00:04:39.957742 kernel: pci 0000:00:02.3: PCI bridge to [bus 04] Sep 5 00:04:39.957838 kernel: pci 0000:00:02.3: bridge window [mem 0xc060200000-0xc0602fffff 64bit pref] Sep 5 00:04:39.957933 kernel: pci 0000:00:02.4: PCI bridge to [bus 05] Sep 5 00:04:39.958036 kernel: pci 0000:00:02.4: bridge window [mem 0x80f00000-0x80ffffff] Sep 5 00:04:39.958132 kernel: pci 0000:00:02.4: bridge window [mem 0xc060300000-0xc0603fffff 64bit pref] Sep 5 00:04:39.958228 kernel: pci 0000:00:02.5: PCI bridge to [bus 06] Sep 5 00:04:39.958323 kernel: pci 0000:00:02.5: bridge window [mem 0x80e00000-0x80efffff] Sep 5 00:04:39.958417 kernel: pci 0000:00:02.5: bridge window [mem 0xc060400000-0xc0604fffff 64bit pref] Sep 5 00:04:39.959586 kernel: pci 0000:07:00.0: ROM [mem 0x80c80000-0x80cfffff pref]: assigned Sep 5 00:04:39.959691 kernel: pci 0000:00:02.6: PCI bridge to [bus 07] Sep 5 00:04:39.959787 kernel: pci 0000:00:02.6: bridge window [io 0x1000-0x1fff] Sep 5 00:04:39.959881 kernel: pci 0000:00:02.6: bridge window [mem 0x80c00000-0x80dfffff] Sep 5 00:04:39.959985 kernel: pci 0000:00:02.6: bridge window [mem 0xc000000000-0xc01fffffff 64bit pref] Sep 5 00:04:39.960080 kernel: pci 0000:00:02.7: PCI bridge to [bus 08] Sep 5 00:04:39.960178 kernel: pci 0000:00:02.7: bridge window [io 0x2000-0x2fff] Sep 5 00:04:39.960273 kernel: pci 0000:00:02.7: bridge window [mem 0x80a00000-0x80bfffff] Sep 5 00:04:39.960370 kernel: pci 0000:00:02.7: bridge window [mem 0xc020000000-0xc03fffffff 64bit pref] Sep 5 00:04:39.961833 kernel: pci 0000:00:03.0: PCI bridge to [bus 09] Sep 5 00:04:39.961944 kernel: pci 0000:00:03.0: bridge window [io 0x3000-0x3fff] Sep 5 00:04:39.962045 kernel: pci 0000:00:03.0: bridge window [mem 0x80800000-0x809fffff] Sep 5 00:04:39.962139 kernel: pci 0000:00:03.0: bridge window [mem 0xc040000000-0xc05fffffff 64bit pref] Sep 5 00:04:39.962240 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 5 00:04:39.962329 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 5 00:04:39.962420 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 5 00:04:39.962529 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xdfffffff window] Sep 5 00:04:39.962619 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Sep 5 00:04:39.962709 kernel: pci_bus 0000:00: resource 9 [mem 0xc000000000-0xc7ffffffff window] Sep 5 00:04:39.962811 kernel: pci_bus 0000:01: resource 1 [mem 0x81200000-0x812fffff] Sep 5 00:04:39.962905 kernel: pci_bus 0000:01: resource 2 [mem 0xc060000000-0xc0600fffff 64bit pref] Sep 5 00:04:39.963012 kernel: pci_bus 0000:02: resource 1 [mem 0x81100000-0x811fffff] Sep 5 00:04:39.963119 kernel: pci_bus 0000:03: resource 1 [mem 0x81000000-0x810fffff] Sep 5 00:04:39.963211 kernel: pci_bus 0000:03: resource 2 [mem 0xc060100000-0xc0601fffff 64bit pref] Sep 5 00:04:39.963310 kernel: pci_bus 0000:04: resource 2 [mem 0xc060200000-0xc0602fffff 64bit pref] Sep 5 00:04:39.963408 kernel: pci_bus 0000:05: resource 1 [mem 0x80f00000-0x80ffffff] Sep 5 00:04:39.964539 kernel: pci_bus 0000:05: resource 2 [mem 0xc060300000-0xc0603fffff 64bit pref] Sep 5 00:04:39.964644 kernel: pci_bus 0000:06: resource 1 [mem 0x80e00000-0x80efffff] Sep 5 00:04:39.964742 kernel: pci_bus 0000:06: resource 2 [mem 0xc060400000-0xc0604fffff 64bit pref] Sep 5 00:04:39.964840 kernel: pci_bus 0000:07: resource 0 [io 0x1000-0x1fff] Sep 5 00:04:39.964933 kernel: pci_bus 0000:07: resource 1 [mem 0x80c00000-0x80dfffff] Sep 5 00:04:39.965035 kernel: pci_bus 0000:07: resource 2 [mem 0xc000000000-0xc01fffffff 64bit pref] Sep 5 00:04:39.965134 kernel: pci_bus 0000:08: resource 0 [io 0x2000-0x2fff] Sep 5 00:04:39.965227 kernel: pci_bus 0000:08: resource 1 [mem 0x80a00000-0x80bfffff] Sep 5 00:04:39.965319 kernel: pci_bus 0000:08: resource 2 [mem 0xc020000000-0xc03fffffff 64bit pref] Sep 5 00:04:39.965424 kernel: pci_bus 0000:09: resource 0 [io 0x3000-0x3fff] Sep 5 00:04:39.966593 kernel: pci_bus 0000:09: resource 1 [mem 0x80800000-0x809fffff] Sep 5 00:04:39.966696 kernel: pci_bus 0000:09: resource 2 [mem 0xc040000000-0xc05fffffff 64bit pref] Sep 5 00:04:39.966705 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 5 00:04:39.966711 kernel: PCI: CLS 0 bytes, default 64 Sep 5 00:04:39.966717 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 5 00:04:39.966723 kernel: software IO TLB: mapped [mem 0x0000000077ffd000-0x000000007bffd000] (64MB) Sep 5 00:04:39.966729 kernel: Initialise system trusted keyrings Sep 5 00:04:39.966739 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 5 00:04:39.966745 kernel: Key type asymmetric registered Sep 5 00:04:39.966750 kernel: Asymmetric key parser 'x509' registered Sep 5 00:04:39.966756 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 5 00:04:39.966762 kernel: io scheduler mq-deadline registered Sep 5 00:04:39.966768 kernel: io scheduler kyber registered Sep 5 00:04:39.966773 kernel: io scheduler bfq registered Sep 5 00:04:39.966874 kernel: pcieport 0000:00:02.0: PME: Signaling with IRQ 24 Sep 5 00:04:39.966980 kernel: pcieport 0000:00:02.0: AER: enabled with IRQ 24 Sep 5 00:04:39.967081 kernel: pcieport 0000:00:02.1: PME: Signaling with IRQ 25 Sep 5 00:04:39.967179 kernel: pcieport 0000:00:02.1: AER: enabled with IRQ 25 Sep 5 00:04:39.967322 kernel: pcieport 0000:00:02.2: PME: Signaling with IRQ 26 Sep 5 00:04:39.968546 kernel: pcieport 0000:00:02.2: AER: enabled with IRQ 26 Sep 5 00:04:39.968696 kernel: pcieport 0000:00:02.3: PME: Signaling with IRQ 27 Sep 5 00:04:39.968831 kernel: pcieport 0000:00:02.3: AER: enabled with IRQ 27 Sep 5 00:04:39.968965 kernel: pcieport 0000:00:02.4: PME: Signaling with IRQ 28 Sep 5 00:04:39.969109 kernel: pcieport 0000:00:02.4: AER: enabled with IRQ 28 Sep 5 00:04:39.969250 kernel: pcieport 0000:00:02.5: PME: Signaling with IRQ 29 Sep 5 00:04:39.969381 kernel: pcieport 0000:00:02.5: AER: enabled with IRQ 29 Sep 5 00:04:39.970564 kernel: pcieport 0000:00:02.6: PME: Signaling with IRQ 30 Sep 5 00:04:39.970705 kernel: pcieport 0000:00:02.6: AER: enabled with IRQ 30 Sep 5 00:04:39.970837 kernel: pcieport 0000:00:02.7: PME: Signaling with IRQ 31 Sep 5 00:04:39.970967 kernel: pcieport 0000:00:02.7: AER: enabled with IRQ 31 Sep 5 00:04:39.970990 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 5 00:04:39.971126 kernel: pcieport 0000:00:03.0: PME: Signaling with IRQ 32 Sep 5 00:04:39.971260 kernel: pcieport 0000:00:03.0: AER: enabled with IRQ 32 Sep 5 00:04:39.971272 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 5 00:04:39.971281 kernel: ACPI: \_SB_.GSIF: Enabled at IRQ 21 Sep 5 00:04:39.971290 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 5 00:04:39.971303 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 5 00:04:39.971312 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 5 00:04:39.971323 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 5 00:04:39.971332 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 5 00:04:39.971341 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 5 00:04:39.976625 kernel: rtc_cmos 00:03: RTC can wake from S4 Sep 5 00:04:39.976820 kernel: rtc_cmos 00:03: registered as rtc0 Sep 5 00:04:39.976959 kernel: rtc_cmos 00:03: setting system clock to 2026-09-05T00:04:39 UTC (1788566679) Sep 5 00:04:39.977100 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 5 00:04:39.977113 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 5 00:04:39.977134 kernel: efifb: probing for efifb Sep 5 00:04:39.977143 kernel: efifb: framebuffer at 0x80000000, using 4032k, total 4032k Sep 5 00:04:39.977154 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Sep 5 00:04:39.977163 kernel: efifb: scrolling: redraw Sep 5 00:04:39.977173 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 5 00:04:39.977182 kernel: Console: switching to colour frame buffer device 160x50 Sep 5 00:04:39.977192 kernel: fb0: EFI VGA frame buffer device Sep 5 00:04:39.977201 kernel: pstore: Using crash dump compression: deflate Sep 5 00:04:39.977209 kernel: pstore: Registered efi_pstore as persistent store backend Sep 5 00:04:39.977222 kernel: NET: Registered PF_INET6 protocol family Sep 5 00:04:39.977230 kernel: Segment Routing with IPv6 Sep 5 00:04:39.977239 kernel: In-situ OAM (IOAM) with IPv6 Sep 5 00:04:39.977248 kernel: NET: Registered PF_PACKET protocol family Sep 5 00:04:39.977256 kernel: Key type dns_resolver registered Sep 5 00:04:39.977265 kernel: IPI shorthand broadcast: enabled Sep 5 00:04:39.977274 kernel: sched_clock: Marking stable (1390016464, 217917693)->(1660506541, -52572384) Sep 5 00:04:39.977283 kernel: registered taskstats version 1 Sep 5 00:04:39.977292 kernel: Loading compiled-in X.509 certificates Sep 5 00:04:39.977304 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.156-flatcar: b30541ee20a7c2326c701c18741253f662a6172a' Sep 5 00:04:39.977313 kernel: Key type .fscrypt registered Sep 5 00:04:39.977322 kernel: Key type fscrypt-provisioning registered Sep 5 00:04:39.977328 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 5 00:04:39.977336 kernel: ima: Allocated hash algorithm: sha1 Sep 5 00:04:39.977345 kernel: ima: No architecture policies found Sep 5 00:04:39.977354 kernel: clk: Disabling unused clocks Sep 5 00:04:39.977362 kernel: Freeing unused kernel image (initmem) memory: 42920K Sep 5 00:04:39.977371 kernel: Write protecting the kernel read-only data: 38912k Sep 5 00:04:39.977383 kernel: Freeing unused kernel image (rodata/data gap) memory: 1804K Sep 5 00:04:39.977392 kernel: Run /init as init process Sep 5 00:04:39.977401 kernel: with arguments: Sep 5 00:04:39.977411 kernel: /init Sep 5 00:04:39.977417 kernel: with environment: Sep 5 00:04:39.977422 kernel: HOME=/ Sep 5 00:04:39.977431 kernel: TERM=linux Sep 5 00:04:39.977443 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 5 00:04:39.977476 systemd[1]: Detected virtualization kvm. Sep 5 00:04:39.977486 systemd[1]: Detected architecture x86-64. Sep 5 00:04:39.977495 systemd[1]: Running in initrd. Sep 5 00:04:39.977504 systemd[1]: No hostname configured, using default hostname. Sep 5 00:04:39.977513 systemd[1]: Hostname set to . Sep 5 00:04:39.977520 systemd[1]: Initializing machine ID from VM UUID. Sep 5 00:04:39.977526 systemd[1]: Queued start job for default target initrd.target. Sep 5 00:04:39.977535 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:04:39.977542 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:04:39.977550 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 5 00:04:39.977556 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 00:04:39.977562 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 5 00:04:39.977568 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 5 00:04:39.977575 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 5 00:04:39.977584 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 5 00:04:39.977590 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:04:39.977596 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:04:39.977602 systemd[1]: Reached target paths.target - Path Units. Sep 5 00:04:39.977608 systemd[1]: Reached target slices.target - Slice Units. Sep 5 00:04:39.977614 systemd[1]: Reached target swap.target - Swaps. Sep 5 00:04:39.977620 systemd[1]: Reached target timers.target - Timer Units. Sep 5 00:04:39.977625 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 00:04:39.977637 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 00:04:39.977649 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 5 00:04:39.977657 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 5 00:04:39.977666 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:04:39.977675 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 00:04:39.977683 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:04:39.977690 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 00:04:39.977696 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 5 00:04:39.977702 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 00:04:39.977710 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 5 00:04:39.977718 systemd[1]: Starting systemd-fsck-usr.service... Sep 5 00:04:39.977727 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 00:04:39.977735 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 00:04:39.977776 systemd-journald[188]: Collecting audit messages is disabled. Sep 5 00:04:39.977796 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:04:39.977802 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 5 00:04:39.977812 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:04:39.977821 systemd[1]: Finished systemd-fsck-usr.service. Sep 5 00:04:39.977830 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 5 00:04:39.977842 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:04:39.977849 systemd-journald[188]: Journal started Sep 5 00:04:39.977863 systemd-journald[188]: Runtime Journal (/run/log/journal/aeb85017312f44f394253023bd623e91) is 8.0M, max 76.2M, 68.2M free. Sep 5 00:04:39.965655 systemd-modules-load[189]: Inserted module 'overlay' Sep 5 00:04:39.982467 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 00:04:39.991923 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 00:04:39.995678 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 00:04:39.996524 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:04:40.006865 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 5 00:04:40.011297 systemd-modules-load[189]: Inserted module 'br_netfilter' Sep 5 00:04:40.011888 kernel: Bridge firewalling registered Sep 5 00:04:40.012591 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 00:04:40.015185 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 00:04:40.019594 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 00:04:40.030820 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:04:40.032447 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:04:40.033712 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:04:40.035123 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:04:40.041092 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 5 00:04:40.043773 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 00:04:40.055049 dracut-cmdline[223]: dracut-dracut-053 Sep 5 00:04:40.059351 dracut-cmdline[223]: 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=9215c603110941002c0250d1c5b0629b89b2cc5fa29e7b45d5a5566f7e6b6ee1 Sep 5 00:04:40.071710 systemd-resolved[226]: Positive Trust Anchors: Sep 5 00:04:40.072335 systemd-resolved[226]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 00:04:40.072778 systemd-resolved[226]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 5 00:04:40.076417 systemd-resolved[226]: Defaulting to hostname 'linux'. Sep 5 00:04:40.077763 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 00:04:40.078701 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:04:40.137488 kernel: SCSI subsystem initialized Sep 5 00:04:40.144531 kernel: Loading iSCSI transport class v2.0-870. Sep 5 00:04:40.153475 kernel: iscsi: registered transport (tcp) Sep 5 00:04:40.170936 kernel: iscsi: registered transport (qla4xxx) Sep 5 00:04:40.171008 kernel: QLogic iSCSI HBA Driver Sep 5 00:04:40.214206 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 5 00:04:40.219586 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 5 00:04:40.243328 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 5 00:04:40.243368 kernel: device-mapper: uevent: version 1.0.3 Sep 5 00:04:40.246500 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 5 00:04:40.284493 kernel: raid6: avx512x4 gen() 37258 MB/s Sep 5 00:04:40.302502 kernel: raid6: avx512x2 gen() 41805 MB/s Sep 5 00:04:40.320503 kernel: raid6: avx512x1 gen() 43362 MB/s Sep 5 00:04:40.338494 kernel: raid6: avx2x4 gen() 47018 MB/s Sep 5 00:04:40.356496 kernel: raid6: avx2x2 gen() 48033 MB/s Sep 5 00:04:40.375581 kernel: raid6: avx2x1 gen() 37142 MB/s Sep 5 00:04:40.375611 kernel: raid6: using algorithm avx2x2 gen() 48033 MB/s Sep 5 00:04:40.395589 kernel: raid6: .... xor() 37457 MB/s, rmw enabled Sep 5 00:04:40.395617 kernel: raid6: using avx512x2 recovery algorithm Sep 5 00:04:40.413497 kernel: xor: automatically using best checksumming function avx Sep 5 00:04:40.521515 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 5 00:04:40.538770 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 5 00:04:40.546753 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:04:40.558648 systemd-udevd[409]: Using default interface naming scheme 'v255'. Sep 5 00:04:40.562577 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:04:40.569677 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 5 00:04:40.591530 dracut-pre-trigger[417]: rd.md=0: removing MD RAID activation Sep 5 00:04:40.631731 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 00:04:40.640767 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 00:04:40.713382 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:04:40.723680 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 5 00:04:40.745376 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 5 00:04:40.748261 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 00:04:40.749206 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:04:40.749906 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 00:04:40.755812 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 5 00:04:40.766597 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 5 00:04:40.813151 kernel: scsi host0: Virtio SCSI HBA Sep 5 00:04:40.826475 kernel: libata version 3.00 loaded. Sep 5 00:04:40.832476 kernel: ahci 0000:00:1f.2: version 3.0 Sep 5 00:04:40.836470 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 5 00:04:40.845932 kernel: scsi 0:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 Sep 5 00:04:40.845995 kernel: cryptd: max_cpu_qlen set to 1000 Sep 5 00:04:40.846004 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Sep 5 00:04:40.846183 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 5 00:04:40.856050 kernel: ACPI: bus type USB registered Sep 5 00:04:40.856081 kernel: usbcore: registered new interface driver usbfs Sep 5 00:04:40.856376 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 5 00:04:40.863051 kernel: usbcore: registered new interface driver hub Sep 5 00:04:40.863066 kernel: scsi host1: ahci Sep 5 00:04:40.863222 kernel: usbcore: registered new device driver usb Sep 5 00:04:40.863235 kernel: scsi host2: ahci Sep 5 00:04:40.856751 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:04:40.864528 kernel: scsi host3: ahci Sep 5 00:04:40.864672 kernel: scsi host4: ahci Sep 5 00:04:40.863881 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 00:04:40.884588 kernel: scsi host5: ahci Sep 5 00:04:40.884789 kernel: scsi host6: ahci Sep 5 00:04:40.884909 kernel: ata1: SATA max UDMA/133 abar m4096@0x81380000 port 0x81380100 irq 48 Sep 5 00:04:40.884918 kernel: ata2: SATA max UDMA/133 abar m4096@0x81380000 port 0x81380180 irq 48 Sep 5 00:04:40.884926 kernel: ata3: SATA max UDMA/133 abar m4096@0x81380000 port 0x81380200 irq 48 Sep 5 00:04:40.884933 kernel: ata4: SATA max UDMA/133 abar m4096@0x81380000 port 0x81380280 irq 48 Sep 5 00:04:40.884941 kernel: ata5: SATA max UDMA/133 abar m4096@0x81380000 port 0x81380300 irq 48 Sep 5 00:04:40.884952 kernel: ata6: SATA max UDMA/133 abar m4096@0x81380000 port 0x81380380 irq 48 Sep 5 00:04:40.866760 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:04:40.867187 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:04:40.884042 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:04:40.893682 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:04:40.905548 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:04:40.905665 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:04:40.911579 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:04:40.921359 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:04:40.926566 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 00:04:40.941261 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:04:41.183504 kernel: ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 5 00:04:41.183599 kernel: ata3: SATA link down (SStatus 0 SControl 300) Sep 5 00:04:41.192137 kernel: ata1.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 5 00:04:41.192204 kernel: ata1.00: applying bridge limits Sep 5 00:04:41.192489 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 5 00:04:41.199508 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 5 00:04:41.199553 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 5 00:04:41.202493 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 5 00:04:41.206609 kernel: ata1.00: configured for UDMA/100 Sep 5 00:04:41.215526 kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 5 00:04:41.271499 kernel: AVX2 version of gcm_enc/dec engaged. Sep 5 00:04:41.274480 kernel: AES CTR mode by8 optimization enabled Sep 5 00:04:41.294477 kernel: sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 5 00:04:41.301633 kernel: sd 0:0:0:0: Power-on or device reset occurred Sep 5 00:04:41.304134 kernel: sd 0:0:0:0: [sda] 160006144 512-byte logical blocks: (81.9 GB/76.3 GiB) Sep 5 00:04:41.308363 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 5 00:04:41.308597 kernel: xhci_hcd 0000:02:00.0: xHCI Host Controller Sep 5 00:04:41.308788 kernel: sd 0:0:0:0: [sda] Mode Sense: 63 00 00 08 Sep 5 00:04:41.308964 kernel: xhci_hcd 0000:02:00.0: new USB bus registered, assigned bus number 1 Sep 5 00:04:41.313277 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 5 00:04:41.313447 kernel: xhci_hcd 0000:02:00.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 Sep 5 00:04:41.319619 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 5 00:04:41.319641 kernel: xhci_hcd 0000:02:00.0: xHCI Host Controller Sep 5 00:04:41.324514 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 5 00:04:41.324722 kernel: xhci_hcd 0000:02:00.0: new USB bus registered, assigned bus number 2 Sep 5 00:04:41.325250 kernel: GPT:17805311 != 160006143 Sep 5 00:04:41.325267 kernel: xhci_hcd 0000:02:00.0: Host supports USB 3.0 SuperSpeed Sep 5 00:04:41.328037 kernel: hub 1-0:1.0: USB hub found Sep 5 00:04:41.328251 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 5 00:04:41.328262 kernel: hub 1-0:1.0: 4 ports detected Sep 5 00:04:41.328384 kernel: GPT:17805311 != 160006143 Sep 5 00:04:41.328404 kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Sep 5 00:04:41.328425 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 5 00:04:41.328433 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 5 00:04:41.330480 kernel: hub 2-0:1.0: USB hub found Sep 5 00:04:41.335125 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 5 00:04:41.335284 kernel: hub 2-0:1.0: 4 ports detected Sep 5 00:04:41.348472 kernel: sr 1:0:0:0: Attached scsi CD-ROM sr0 Sep 5 00:04:41.386471 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/sda6 scanned by (udev-worker) (459) Sep 5 00:04:41.387792 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - QEMU_HARDDISK EFI-SYSTEM. Sep 5 00:04:41.394497 kernel: BTRFS: device fsid 7334055a-467a-40fa-9177-3daf8468e49a devid 1 transid 31 /dev/sda3 scanned by (udev-worker) (467) Sep 5 00:04:41.394629 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - QEMU_HARDDISK ROOT. Sep 5 00:04:41.400598 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - QEMU_HARDDISK OEM. Sep 5 00:04:41.406369 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - QEMU_HARDDISK USR-A. Sep 5 00:04:41.406912 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - QEMU_HARDDISK USR-A. Sep 5 00:04:41.412599 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 5 00:04:41.419945 disk-uuid[587]: Primary Header is updated. Sep 5 00:04:41.419945 disk-uuid[587]: Secondary Entries is updated. Sep 5 00:04:41.419945 disk-uuid[587]: Secondary Header is updated. Sep 5 00:04:41.425562 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 5 00:04:41.429471 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 5 00:04:41.435476 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 5 00:04:41.566540 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Sep 5 00:04:41.711781 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 5 00:04:41.722325 kernel: usbcore: registered new interface driver usbhid Sep 5 00:04:41.722624 kernel: usbhid: USB HID core driver Sep 5 00:04:41.731471 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 Sep 5 00:04:41.731530 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 Sep 5 00:04:42.445543 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 5 00:04:42.447105 disk-uuid[589]: The operation has completed successfully. Sep 5 00:04:42.524316 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 5 00:04:42.524418 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 5 00:04:42.529651 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 5 00:04:42.533183 sh[608]: Success Sep 5 00:04:42.544473 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Sep 5 00:04:42.596935 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 5 00:04:42.599947 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 5 00:04:42.601616 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 5 00:04:42.619626 kernel: BTRFS info (device dm-0): first mount of filesystem 7334055a-467a-40fa-9177-3daf8468e49a Sep 5 00:04:42.619661 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:04:42.624151 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 5 00:04:42.624170 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 5 00:04:42.626125 kernel: BTRFS info (device dm-0): using free space tree Sep 5 00:04:42.640492 kernel: BTRFS info (device dm-0): enabling ssd optimizations Sep 5 00:04:42.642065 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 5 00:04:42.642859 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 5 00:04:42.651596 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 5 00:04:42.654564 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 5 00:04:42.676792 kernel: BTRFS info (device sda6): first mount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:04:42.676833 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:04:42.683886 kernel: BTRFS info (device sda6): using free space tree Sep 5 00:04:42.691621 kernel: BTRFS info (device sda6): enabling ssd optimizations Sep 5 00:04:42.691647 kernel: BTRFS info (device sda6): auto enabling async discard Sep 5 00:04:42.701776 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 5 00:04:42.704490 kernel: BTRFS info (device sda6): last unmount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:04:42.708101 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 5 00:04:42.713570 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 5 00:04:42.745868 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 00:04:42.755575 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 00:04:42.775787 systemd-networkd[790]: lo: Link UP Sep 5 00:04:42.776366 systemd-networkd[790]: lo: Gained carrier Sep 5 00:04:42.779041 systemd-networkd[790]: Enumeration completed Sep 5 00:04:42.779623 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 00:04:42.780051 systemd[1]: Reached target network.target - Network. Sep 5 00:04:42.780504 ignition[739]: Ignition 2.19.0 Sep 5 00:04:42.781378 systemd-networkd[790]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:42.780514 ignition[739]: Stage: fetch-offline Sep 5 00:04:42.781382 systemd-networkd[790]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:04:42.780543 ignition[739]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:04:42.783311 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 00:04:42.780552 ignition[739]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Sep 5 00:04:42.783356 systemd-networkd[790]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:42.780616 ignition[739]: parsed url from cmdline: "" Sep 5 00:04:42.783359 systemd-networkd[790]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:04:42.780620 ignition[739]: no config URL provided Sep 5 00:04:42.784172 systemd-networkd[790]: eth0: Link UP Sep 5 00:04:42.780624 ignition[739]: reading system config file "/usr/lib/ignition/user.ign" Sep 5 00:04:42.784176 systemd-networkd[790]: eth0: Gained carrier Sep 5 00:04:42.780632 ignition[739]: no config at "/usr/lib/ignition/user.ign" Sep 5 00:04:42.784182 systemd-networkd[790]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:42.780636 ignition[739]: failed to fetch config: resource requires networking Sep 5 00:04:42.787666 systemd-networkd[790]: eth1: Link UP Sep 5 00:04:42.780752 ignition[739]: Ignition finished successfully Sep 5 00:04:42.787670 systemd-networkd[790]: eth1: Gained carrier Sep 5 00:04:42.787677 systemd-networkd[790]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:42.791778 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 5 00:04:42.801418 ignition[798]: Ignition 2.19.0 Sep 5 00:04:42.801427 ignition[798]: Stage: fetch Sep 5 00:04:42.801571 ignition[798]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:04:42.801581 ignition[798]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Sep 5 00:04:42.801646 ignition[798]: parsed url from cmdline: "" Sep 5 00:04:42.801649 ignition[798]: no config URL provided Sep 5 00:04:42.801656 ignition[798]: reading system config file "/usr/lib/ignition/user.ign" Sep 5 00:04:42.801663 ignition[798]: no config at "/usr/lib/ignition/user.ign" Sep 5 00:04:42.801677 ignition[798]: GET http://169.254.169.254/hetzner/v1/userdata: attempt #1 Sep 5 00:04:42.801908 ignition[798]: GET error: Get "http://169.254.169.254/hetzner/v1/userdata": dial tcp 169.254.169.254:80: connect: network is unreachable Sep 5 00:04:42.818496 systemd-networkd[790]: eth1: DHCPv4 address 10.0.0.4/32 acquired from 10.0.0.1 Sep 5 00:04:42.856493 systemd-networkd[790]: eth0: DHCPv4 address 37.27.36.105/32, gateway 172.31.1.1 acquired from 172.31.1.1 Sep 5 00:04:43.002142 ignition[798]: GET http://169.254.169.254/hetzner/v1/userdata: attempt #2 Sep 5 00:04:43.008657 ignition[798]: GET result: OK Sep 5 00:04:43.008751 ignition[798]: parsing config with SHA512: bb42b23b38745e3575ce408d410bd9347112abe55c6fc48cf1ee53e8d1df8a35c63ff6290b3141a19746f0b1c200a94038651a2d888c2d763b102306ddb0a316 Sep 5 00:04:43.013315 unknown[798]: fetched base config from "system" Sep 5 00:04:43.014246 unknown[798]: fetched base config from "system" Sep 5 00:04:43.014290 unknown[798]: fetched user config from "hetzner" Sep 5 00:04:43.014823 ignition[798]: fetch: fetch complete Sep 5 00:04:43.014839 ignition[798]: fetch: fetch passed Sep 5 00:04:43.014940 ignition[798]: Ignition finished successfully Sep 5 00:04:43.020718 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 5 00:04:43.026703 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 5 00:04:43.056076 ignition[805]: Ignition 2.19.0 Sep 5 00:04:43.056094 ignition[805]: Stage: kargs Sep 5 00:04:43.058738 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 5 00:04:43.056260 ignition[805]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:04:43.056271 ignition[805]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Sep 5 00:04:43.056937 ignition[805]: kargs: kargs passed Sep 5 00:04:43.056995 ignition[805]: Ignition finished successfully Sep 5 00:04:43.071727 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 5 00:04:43.089568 ignition[811]: Ignition 2.19.0 Sep 5 00:04:43.090506 ignition[811]: Stage: disks Sep 5 00:04:43.090728 ignition[811]: no configs at "/usr/lib/ignition/base.d" Sep 5 00:04:43.090740 ignition[811]: no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Sep 5 00:04:43.093731 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 5 00:04:43.092075 ignition[811]: disks: disks passed Sep 5 00:04:43.095003 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 5 00:04:43.092121 ignition[811]: Ignition finished successfully Sep 5 00:04:43.096144 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 5 00:04:43.097315 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 00:04:43.098528 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 00:04:43.099718 systemd[1]: Reached target basic.target - Basic System. Sep 5 00:04:43.105589 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 5 00:04:43.134300 systemd-fsck[819]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Sep 5 00:04:43.139421 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 5 00:04:43.144899 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 5 00:04:43.220476 kernel: EXT4-fs (sda9): mounted filesystem 8c18da49-3eb0-4eeb-934e-6ac91f8051aa r/w with ordered data mode. Quota mode: none. Sep 5 00:04:43.221136 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 5 00:04:43.222241 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 5 00:04:43.238533 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 00:04:43.241223 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 5 00:04:43.244838 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 5 00:04:43.246303 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 5 00:04:43.246332 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 00:04:43.248482 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 5 00:04:43.252860 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/sda6 scanned by mount (827) Sep 5 00:04:43.253478 kernel: BTRFS info (device sda6): first mount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:04:43.253507 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:04:43.253520 kernel: BTRFS info (device sda6): using free space tree Sep 5 00:04:43.264468 kernel: BTRFS info (device sda6): enabling ssd optimizations Sep 5 00:04:43.264494 kernel: BTRFS info (device sda6): auto enabling async discard Sep 5 00:04:43.274600 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 5 00:04:43.275998 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 00:04:43.302758 coreos-metadata[829]: Sep 05 00:04:43.302 INFO Fetching http://169.254.169.254/hetzner/v1/metadata/hostname: Attempt #1 Sep 5 00:04:43.304633 coreos-metadata[829]: Sep 05 00:04:43.304 INFO Fetch successful Sep 5 00:04:43.305250 coreos-metadata[829]: Sep 05 00:04:43.305 INFO wrote hostname ci-4081-3-10--85aa94332c to /sysroot/etc/hostname Sep 5 00:04:43.306558 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 5 00:04:43.313608 initrd-setup-root[855]: cut: /sysroot/etc/passwd: No such file or directory Sep 5 00:04:43.318010 initrd-setup-root[862]: cut: /sysroot/etc/group: No such file or directory Sep 5 00:04:43.322162 initrd-setup-root[869]: cut: /sysroot/etc/shadow: No such file or directory Sep 5 00:04:43.325385 initrd-setup-root[876]: cut: /sysroot/etc/gshadow: No such file or directory Sep 5 00:04:43.401434 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 5 00:04:43.409545 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 5 00:04:43.413350 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 5 00:04:43.420469 kernel: BTRFS info (device sda6): last unmount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:04:43.436925 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 5 00:04:43.441490 ignition[944]: INFO : Ignition 2.19.0 Sep 5 00:04:43.442087 ignition[944]: INFO : Stage: mount Sep 5 00:04:43.442480 ignition[944]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:04:43.442480 ignition[944]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Sep 5 00:04:43.443234 ignition[944]: INFO : mount: mount passed Sep 5 00:04:43.443234 ignition[944]: INFO : Ignition finished successfully Sep 5 00:04:43.443603 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 5 00:04:43.448533 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 5 00:04:43.618572 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 5 00:04:43.624721 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 00:04:43.656510 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/sda6 scanned by mount (956) Sep 5 00:04:43.666106 kernel: BTRFS info (device sda6): first mount of filesystem 40ce7b63-c029-41bb-9ac2-b503fd73bd4d Sep 5 00:04:43.666188 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 00:04:43.671656 kernel: BTRFS info (device sda6): using free space tree Sep 5 00:04:43.687762 kernel: BTRFS info (device sda6): enabling ssd optimizations Sep 5 00:04:43.687844 kernel: BTRFS info (device sda6): auto enabling async discard Sep 5 00:04:43.693206 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 00:04:43.732195 ignition[973]: INFO : Ignition 2.19.0 Sep 5 00:04:43.732195 ignition[973]: INFO : Stage: files Sep 5 00:04:43.734253 ignition[973]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:04:43.734253 ignition[973]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Sep 5 00:04:43.734253 ignition[973]: DEBUG : files: compiled without relabeling support, skipping Sep 5 00:04:43.736779 ignition[973]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 5 00:04:43.736779 ignition[973]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 5 00:04:43.739525 ignition[973]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 5 00:04:43.740640 ignition[973]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 5 00:04:43.742165 unknown[973]: wrote ssh authorized keys file for user: core Sep 5 00:04:43.743326 ignition[973]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 5 00:04:43.745183 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 5 00:04:43.746051 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 5 00:04:43.746839 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 00:04:43.746839 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 00:04:43.746839 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Sep 5 00:04:43.749812 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Sep 5 00:04:43.749812 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Sep 5 00:04:43.749812 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.34.4-x86-64.raw: attempt #1 Sep 5 00:04:43.920870 systemd-networkd[790]: eth0: Gained IPv6LL Sep 5 00:04:44.323748 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 5 00:04:44.599020 ignition[973]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Sep 5 00:04:44.599020 ignition[973]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 5 00:04:44.601832 ignition[973]: 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" Sep 5 00:04:44.601832 ignition[973]: 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" Sep 5 00:04:44.601832 ignition[973]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 5 00:04:44.601832 ignition[973]: INFO : files: createResultFile: createFiles: op(9): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 5 00:04:44.601832 ignition[973]: INFO : files: createResultFile: createFiles: op(9): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 5 00:04:44.601832 ignition[973]: INFO : files: files passed Sep 5 00:04:44.601832 ignition[973]: INFO : Ignition finished successfully Sep 5 00:04:44.602196 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 5 00:04:44.609608 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 5 00:04:44.614491 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 5 00:04:44.617579 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 5 00:04:44.617783 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 5 00:04:44.626721 initrd-setup-root-after-ignition[1002]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:04:44.626721 initrd-setup-root-after-ignition[1002]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:04:44.629037 initrd-setup-root-after-ignition[1006]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 00:04:44.630095 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 00:04:44.630900 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 5 00:04:44.636581 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 5 00:04:44.661087 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 5 00:04:44.661278 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 5 00:04:44.663199 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 5 00:04:44.664434 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 5 00:04:44.666269 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 5 00:04:44.671655 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 5 00:04:44.684404 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 00:04:44.690678 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 5 00:04:44.703300 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:04:44.704588 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:04:44.705852 systemd[1]: Stopped target timers.target - Timer Units. Sep 5 00:04:44.706757 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 5 00:04:44.706957 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 00:04:44.708328 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 5 00:04:44.709111 systemd[1]: Stopped target basic.target - Basic System. Sep 5 00:04:44.709854 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 5 00:04:44.710552 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 00:04:44.711244 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 5 00:04:44.712018 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 5 00:04:44.712705 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 00:04:44.713416 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 5 00:04:44.714132 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 5 00:04:44.714855 systemd[1]: Stopped target swap.target - Swaps. Sep 5 00:04:44.715566 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 5 00:04:44.715646 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 5 00:04:44.716669 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:04:44.717404 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:04:44.718175 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 5 00:04:44.718481 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:04:44.719257 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 5 00:04:44.719332 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 5 00:04:44.720337 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 5 00:04:44.720419 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 00:04:44.721060 systemd[1]: ignition-files.service: Deactivated successfully. Sep 5 00:04:44.721129 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 5 00:04:44.721753 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 5 00:04:44.721821 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 5 00:04:44.730639 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 5 00:04:44.732498 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 5 00:04:44.734486 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 5 00:04:44.734997 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:04:44.736388 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 5 00:04:44.736802 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 00:04:44.742481 ignition[1026]: INFO : Ignition 2.19.0 Sep 5 00:04:44.742481 ignition[1026]: INFO : Stage: umount Sep 5 00:04:44.746084 ignition[1026]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 00:04:44.746084 ignition[1026]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/hetzner" Sep 5 00:04:44.746084 ignition[1026]: INFO : umount: umount passed Sep 5 00:04:44.746084 ignition[1026]: INFO : Ignition finished successfully Sep 5 00:04:44.744696 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 5 00:04:44.744801 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 5 00:04:44.745877 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 5 00:04:44.745991 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 5 00:04:44.748102 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 5 00:04:44.748175 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 5 00:04:44.749353 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 5 00:04:44.749394 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 5 00:04:44.752545 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 5 00:04:44.752587 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 5 00:04:44.753542 systemd-networkd[790]: eth1: Gained IPv6LL Sep 5 00:04:44.754161 systemd[1]: Stopped target network.target - Network. Sep 5 00:04:44.754950 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 5 00:04:44.755004 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 00:04:44.756951 systemd[1]: Stopped target paths.target - Path Units. Sep 5 00:04:44.757603 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 5 00:04:44.761506 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:04:44.761856 systemd[1]: Stopped target slices.target - Slice Units. Sep 5 00:04:44.762754 systemd[1]: Stopped target sockets.target - Socket Units. Sep 5 00:04:44.764060 systemd[1]: iscsid.socket: Deactivated successfully. Sep 5 00:04:44.764101 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 00:04:44.764444 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 5 00:04:44.764493 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 00:04:44.764819 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 5 00:04:44.764862 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 5 00:04:44.765209 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 5 00:04:44.765241 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 5 00:04:44.765714 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 5 00:04:44.766306 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 5 00:04:44.767535 systemd-networkd[790]: eth1: DHCPv6 lease lost Sep 5 00:04:44.768120 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 5 00:04:44.768643 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 5 00:04:44.768724 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 5 00:04:44.769802 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 5 00:04:44.769870 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 5 00:04:44.772513 systemd-networkd[790]: eth0: DHCPv6 lease lost Sep 5 00:04:44.774145 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 5 00:04:44.774267 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 5 00:04:44.776330 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 5 00:04:44.776427 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 5 00:04:44.779086 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 5 00:04:44.779143 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:04:44.783532 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 5 00:04:44.784268 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 5 00:04:44.784321 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 00:04:44.785571 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 5 00:04:44.785615 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:04:44.786009 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 5 00:04:44.786048 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 5 00:04:44.787532 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 5 00:04:44.787576 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:04:44.788170 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:04:44.801134 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 5 00:04:44.801237 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 5 00:04:44.804969 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 5 00:04:44.805122 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:04:44.805949 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 5 00:04:44.806015 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 5 00:04:44.806821 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 5 00:04:44.806854 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:04:44.807441 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 5 00:04:44.807507 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 5 00:04:44.808473 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 5 00:04:44.808523 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 5 00:04:44.810402 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 5 00:04:44.810446 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 00:04:44.818669 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 5 00:04:44.819206 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 5 00:04:44.819267 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:04:44.819851 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 5 00:04:44.819900 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:04:44.820487 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 5 00:04:44.820534 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:04:44.821570 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:04:44.821621 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:04:44.828237 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 5 00:04:44.828353 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 5 00:04:44.829605 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 5 00:04:44.836667 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 5 00:04:44.843634 systemd[1]: Switching root. Sep 5 00:04:44.873312 systemd-journald[188]: Journal stopped Sep 5 00:04:45.949330 systemd-journald[188]: Received SIGTERM from PID 1 (systemd). Sep 5 00:04:45.949398 kernel: SELinux: policy capability network_peer_controls=1 Sep 5 00:04:45.949409 kernel: SELinux: policy capability open_perms=1 Sep 5 00:04:45.949418 kernel: SELinux: policy capability extended_socket_class=1 Sep 5 00:04:45.949426 kernel: SELinux: policy capability always_check_network=0 Sep 5 00:04:45.949434 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 5 00:04:45.949443 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 5 00:04:45.950884 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 5 00:04:45.950907 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 5 00:04:45.950916 kernel: audit: type=1403 audit(1788566685.062:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 5 00:04:45.950927 systemd[1]: Successfully loaded SELinux policy in 58.683ms. Sep 5 00:04:45.952490 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 13.799ms. Sep 5 00:04:45.952514 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 5 00:04:45.952524 systemd[1]: Detected virtualization kvm. Sep 5 00:04:45.952537 systemd[1]: Detected architecture x86-64. Sep 5 00:04:45.952552 systemd[1]: Detected first boot. Sep 5 00:04:45.952561 systemd[1]: Hostname set to . Sep 5 00:04:45.952570 systemd[1]: Initializing machine ID from VM UUID. Sep 5 00:04:45.952579 zram_generator::config[1068]: No configuration found. Sep 5 00:04:45.952589 systemd[1]: Populated /etc with preset unit settings. Sep 5 00:04:45.952598 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 5 00:04:45.952607 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 5 00:04:45.952617 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 5 00:04:45.952627 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 5 00:04:45.952636 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 5 00:04:45.952644 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 5 00:04:45.952653 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 5 00:04:45.952662 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 5 00:04:45.952671 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 5 00:04:45.952680 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 5 00:04:45.952695 systemd[1]: Created slice user.slice - User and Session Slice. Sep 5 00:04:45.952707 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 00:04:45.952720 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 00:04:45.952735 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 5 00:04:45.952746 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 5 00:04:45.952758 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 5 00:04:45.952772 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 00:04:45.952785 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 5 00:04:45.952797 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 00:04:45.952813 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 5 00:04:45.952826 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 5 00:04:45.952839 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 5 00:04:45.952850 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 5 00:04:45.952858 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 00:04:45.952868 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 00:04:45.952879 systemd[1]: Reached target slices.target - Slice Units. Sep 5 00:04:45.952888 systemd[1]: Reached target swap.target - Swaps. Sep 5 00:04:45.952896 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 5 00:04:45.952905 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 5 00:04:45.952913 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 00:04:45.952926 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 00:04:45.952935 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 00:04:45.952944 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 5 00:04:45.952956 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 5 00:04:45.952969 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 5 00:04:45.952992 systemd[1]: Mounting media.mount - External Media Directory... Sep 5 00:04:45.953006 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:45.953017 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 5 00:04:45.953028 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 5 00:04:45.953038 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 5 00:04:45.953049 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 5 00:04:45.953058 systemd[1]: Reached target machines.target - Containers. Sep 5 00:04:45.953067 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 5 00:04:45.953078 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:04:45.953086 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 00:04:45.953095 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 5 00:04:45.953104 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:04:45.953113 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 00:04:45.953121 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:04:45.953129 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 5 00:04:45.953138 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:04:45.953149 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 5 00:04:45.953158 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 5 00:04:45.953167 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 5 00:04:45.953175 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 5 00:04:45.953185 systemd[1]: Stopped systemd-fsck-usr.service. Sep 5 00:04:45.953193 kernel: fuse: init (API version 7.39) Sep 5 00:04:45.953203 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 00:04:45.953213 kernel: loop: module loaded Sep 5 00:04:45.953224 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 00:04:45.953233 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 5 00:04:45.953242 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 5 00:04:45.953250 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 00:04:45.953259 systemd[1]: verity-setup.service: Deactivated successfully. Sep 5 00:04:45.953268 systemd[1]: Stopped verity-setup.service. Sep 5 00:04:45.953277 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:45.953307 systemd-journald[1144]: Collecting audit messages is disabled. Sep 5 00:04:45.953330 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 5 00:04:45.953338 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 5 00:04:45.953347 systemd-journald[1144]: Journal started Sep 5 00:04:45.953365 systemd-journald[1144]: Runtime Journal (/run/log/journal/aeb85017312f44f394253023bd623e91) is 8.0M, max 76.2M, 68.2M free. Sep 5 00:04:45.607798 systemd[1]: Queued start job for default target multi-user.target. Sep 5 00:04:45.624652 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 5 00:04:45.625208 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 5 00:04:45.958675 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 00:04:45.957315 systemd[1]: Mounted media.mount - External Media Directory. Sep 5 00:04:45.957812 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 5 00:04:45.958278 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 5 00:04:45.958916 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 5 00:04:45.959598 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 5 00:04:45.960300 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 00:04:45.961005 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 5 00:04:45.961139 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 5 00:04:45.962024 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:04:45.962157 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:04:45.963069 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:04:45.963204 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:04:45.963844 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 5 00:04:45.963996 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 5 00:04:45.964768 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:04:45.964962 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:04:45.965763 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 00:04:45.966387 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 00:04:45.967106 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 5 00:04:45.972065 kernel: ACPI: bus type drm_connector registered Sep 5 00:04:45.975216 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 00:04:45.975670 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 00:04:45.981313 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 5 00:04:45.988772 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 5 00:04:45.994573 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 5 00:04:45.995512 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 5 00:04:45.995548 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 00:04:45.999528 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 5 00:04:46.006871 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 5 00:04:46.015499 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 5 00:04:46.016649 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:04:46.020064 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 5 00:04:46.024753 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 5 00:04:46.026446 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 00:04:46.031689 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 5 00:04:46.032096 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 00:04:46.034441 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 00:04:46.041712 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 5 00:04:46.047611 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 5 00:04:46.050825 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 5 00:04:46.051559 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 5 00:04:46.054194 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 5 00:04:46.075535 kernel: loop0: detected capacity change from 0 to 8 Sep 5 00:04:46.082562 systemd-journald[1144]: Time spent on flushing to /var/log/journal/aeb85017312f44f394253023bd623e91 is 22.542ms for 1177 entries. Sep 5 00:04:46.082562 systemd-journald[1144]: System Journal (/var/log/journal/aeb85017312f44f394253023bd623e91) is 8.0M, max 584.8M, 576.8M free. Sep 5 00:04:46.132823 systemd-journald[1144]: Received client request to flush runtime journal. Sep 5 00:04:46.132880 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 5 00:04:46.132899 kernel: loop1: detected capacity change from 0 to 142488 Sep 5 00:04:46.095488 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 5 00:04:46.096013 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 5 00:04:46.107052 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 5 00:04:46.148672 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 5 00:04:46.169429 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 5 00:04:46.175802 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 5 00:04:46.191795 systemd-tmpfiles[1189]: ACLs are not supported, ignoring. Sep 5 00:04:46.191817 systemd-tmpfiles[1189]: ACLs are not supported, ignoring. Sep 5 00:04:46.198995 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 00:04:46.202940 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 00:04:46.208746 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 5 00:04:46.211485 kernel: loop2: detected capacity change from 0 to 219192 Sep 5 00:04:46.260799 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 00:04:46.267715 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 5 00:04:46.271720 kernel: loop3: detected capacity change from 0 to 140768 Sep 5 00:04:46.291257 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 5 00:04:46.300437 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 00:04:46.302166 udevadm[1214]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 5 00:04:46.317531 kernel: loop4: detected capacity change from 0 to 8 Sep 5 00:04:46.323501 kernel: loop5: detected capacity change from 0 to 142488 Sep 5 00:04:46.328317 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Sep 5 00:04:46.328670 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Sep 5 00:04:46.343906 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 00:04:46.345619 kernel: loop6: detected capacity change from 0 to 219192 Sep 5 00:04:46.364478 kernel: loop7: detected capacity change from 0 to 140768 Sep 5 00:04:46.378784 (sd-merge)[1219]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-hetzner'. Sep 5 00:04:46.379792 (sd-merge)[1219]: Merged extensions into '/usr'. Sep 5 00:04:46.385699 systemd[1]: Reloading requested from client PID 1188 ('systemd-sysext') (unit systemd-sysext.service)... Sep 5 00:04:46.385809 systemd[1]: Reloading... Sep 5 00:04:46.481477 zram_generator::config[1249]: No configuration found. Sep 5 00:04:46.542941 ldconfig[1183]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 5 00:04:46.588736 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 5 00:04:46.625727 systemd[1]: Reloading finished in 239 ms. Sep 5 00:04:46.648039 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 5 00:04:46.648962 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 5 00:04:46.649648 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 5 00:04:46.658601 systemd[1]: Starting ensure-sysext.service... Sep 5 00:04:46.660334 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 00:04:46.662594 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 00:04:46.676510 systemd[1]: Reloading requested from client PID 1290 ('systemctl') (unit ensure-sysext.service)... Sep 5 00:04:46.676533 systemd[1]: Reloading... Sep 5 00:04:46.698623 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 5 00:04:46.699229 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 5 00:04:46.700112 systemd-tmpfiles[1291]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 5 00:04:46.700374 systemd-tmpfiles[1291]: ACLs are not supported, ignoring. Sep 5 00:04:46.700547 systemd-tmpfiles[1291]: ACLs are not supported, ignoring. Sep 5 00:04:46.702428 systemd-udevd[1292]: Using default interface naming scheme 'v255'. Sep 5 00:04:46.705375 systemd-tmpfiles[1291]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 00:04:46.705505 systemd-tmpfiles[1291]: Skipping /boot Sep 5 00:04:46.717083 systemd-tmpfiles[1291]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 00:04:46.717168 systemd-tmpfiles[1291]: Skipping /boot Sep 5 00:04:46.753533 zram_generator::config[1317]: No configuration found. Sep 5 00:04:46.872586 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 31 scanned by (udev-worker) (1327) Sep 5 00:04:46.928247 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 5 00:04:46.985683 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 5 00:04:46.992538 kernel: [drm] pci: virtio-vga detected at 0000:00:01.0 Sep 5 00:04:46.994058 kernel: Console: switching to colour dummy device 80x25 Sep 5 00:04:46.995476 kernel: virtio-pci 0000:00:01.0: vgaarb: deactivate vga console Sep 5 00:04:46.998490 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Sep 5 00:04:46.998529 kernel: [drm] features: -context_init Sep 5 00:04:47.000466 kernel: [drm] number of scanouts: 1 Sep 5 00:04:47.000500 kernel: [drm] number of cap sets: 0 Sep 5 00:04:47.003476 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:01.0 on minor 0 Sep 5 00:04:47.011485 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Sep 5 00:04:47.011569 kernel: Console: switching to colour frame buffer device 160x50 Sep 5 00:04:47.022487 kernel: virtio-pci 0000:00:01.0: [drm] fb0: virtio_gpudrmfb frame buffer device Sep 5 00:04:47.030411 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 5 00:04:47.030865 systemd[1]: Reloading finished in 353 ms. Sep 5 00:04:47.037523 kernel: mousedev: PS/2 mouse device common for all mice Sep 5 00:04:47.051583 kernel: ACPI: button: Power Button [PWRF] Sep 5 00:04:47.060631 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 00:04:47.063279 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 00:04:47.076371 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Sep 5 00:04:47.076446 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Sep 5 00:04:47.079481 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 5 00:04:47.084653 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Sep 5 00:04:47.084871 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 5 00:04:47.096635 kernel: EDAC MC: Ver: 3.0.0 Sep 5 00:04:47.116357 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. Sep 5 00:04:47.119104 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - QEMU_HARDDISK OEM. Sep 5 00:04:47.122086 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:47.130963 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 5 00:04:47.140157 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 5 00:04:47.141070 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:04:47.144640 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:04:47.153700 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:04:47.157977 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:04:47.159592 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:04:47.164694 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 5 00:04:47.166528 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 5 00:04:47.173530 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 00:04:47.183759 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 00:04:47.187547 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 5 00:04:47.188361 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:47.192207 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:04:47.192924 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:04:47.200767 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 5 00:04:47.211136 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:04:47.211352 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:04:47.220897 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:04:47.221120 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:04:47.240827 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:47.241431 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:04:47.250632 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:04:47.252725 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:04:47.258514 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:04:47.261232 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:04:47.270823 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 5 00:04:47.274572 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:04:47.275638 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:47.278950 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 5 00:04:47.283354 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:04:47.283557 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:04:47.284820 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:04:47.284976 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:04:47.287270 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:04:47.288077 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:04:47.297752 augenrules[1439]: No rules Sep 5 00:04:47.308440 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 5 00:04:47.314092 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 5 00:04:47.327496 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:47.328651 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 00:04:47.334736 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 00:04:47.340749 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 00:04:47.343772 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 00:04:47.347097 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 00:04:47.348581 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 00:04:47.353723 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 5 00:04:47.354995 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 5 00:04:47.355102 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 00:04:47.355941 systemd[1]: Finished ensure-sysext.service. Sep 5 00:04:47.362072 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 5 00:04:47.366134 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 5 00:04:47.385810 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 5 00:04:47.394740 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 00:04:47.396692 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:04:47.410692 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 00:04:47.413273 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 5 00:04:47.416322 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 00:04:47.416511 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 00:04:47.417260 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 00:04:47.417399 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 00:04:47.424796 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 00:04:47.425005 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 00:04:47.426211 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 5 00:04:47.433051 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 00:04:47.434953 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 00:04:47.444629 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 5 00:04:47.446607 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 00:04:47.446692 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 00:04:47.471270 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 00:04:47.473980 lvm[1477]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 5 00:04:47.505527 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 5 00:04:47.508367 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 00:04:47.515431 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 5 00:04:47.532622 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 5 00:04:47.533168 systemd-networkd[1411]: lo: Link UP Sep 5 00:04:47.533172 systemd-networkd[1411]: lo: Gained carrier Sep 5 00:04:47.534672 systemd[1]: Reached target time-set.target - System Time Set. Sep 5 00:04:47.541183 lvm[1484]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 5 00:04:47.543013 systemd-networkd[1411]: Enumeration completed Sep 5 00:04:47.543384 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 00:04:47.545032 systemd-resolved[1413]: Positive Trust Anchors: Sep 5 00:04:47.545253 systemd-resolved[1413]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 00:04:47.545277 systemd-resolved[1413]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 5 00:04:47.546061 systemd-networkd[1411]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:47.546070 systemd-networkd[1411]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:04:47.546878 systemd-networkd[1411]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:47.546884 systemd-networkd[1411]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 00:04:47.548770 systemd-resolved[1413]: Using system hostname 'ci-4081-3-10--85aa94332c'. Sep 5 00:04:47.551550 systemd-networkd[1411]: eth0: Link UP Sep 5 00:04:47.551597 systemd-networkd[1411]: eth0: Gained carrier Sep 5 00:04:47.551648 systemd-networkd[1411]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:47.554624 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 5 00:04:47.558710 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 00:04:47.560283 systemd[1]: Reached target network.target - Network. Sep 5 00:04:47.560655 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 00:04:47.561007 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 00:04:47.561441 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 5 00:04:47.562315 systemd-networkd[1411]: eth1: Link UP Sep 5 00:04:47.562321 systemd-networkd[1411]: eth1: Gained carrier Sep 5 00:04:47.562343 systemd-networkd[1411]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 00:04:47.564261 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 5 00:04:47.564819 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 5 00:04:47.565248 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 5 00:04:47.566270 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 5 00:04:47.566832 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 5 00:04:47.566855 systemd[1]: Reached target paths.target - Path Units. Sep 5 00:04:47.567185 systemd[1]: Reached target timers.target - Timer Units. Sep 5 00:04:47.569196 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 5 00:04:47.571841 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 5 00:04:47.577189 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 5 00:04:47.579949 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 5 00:04:47.580719 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 5 00:04:47.582409 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 00:04:47.584155 systemd[1]: Reached target basic.target - Basic System. Sep 5 00:04:47.585234 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 5 00:04:47.585269 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 5 00:04:47.593743 systemd[1]: Starting containerd.service - containerd container runtime... Sep 5 00:04:47.595547 systemd-networkd[1411]: eth1: DHCPv4 address 10.0.0.4/32 acquired from 10.0.0.1 Sep 5 00:04:47.596369 systemd-timesyncd[1464]: Network configuration changed, trying to establish connection. Sep 5 00:04:47.599640 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 5 00:04:47.602617 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 5 00:04:47.606013 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 5 00:04:47.609610 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 5 00:04:47.611221 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 5 00:04:47.613209 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 5 00:04:47.616637 systemd[1]: Started qemu-guest-agent.service - QEMU Guest Agent. Sep 5 00:04:47.620890 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 5 00:04:47.623204 systemd-networkd[1411]: eth0: DHCPv4 address 37.27.36.105/32, gateway 172.31.1.1 acquired from 172.31.1.1 Sep 5 00:04:47.627980 systemd-timesyncd[1464]: Network configuration changed, trying to establish connection. Sep 5 00:04:47.632697 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 5 00:04:47.644601 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 5 00:04:47.649087 jq[1495]: false Sep 5 00:04:47.651649 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 5 00:04:47.652833 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 5 00:04:47.655476 coreos-metadata[1491]: Sep 05 00:04:47.654 INFO Fetching http://169.254.169.254/hetzner/v1/metadata: Attempt #1 Sep 5 00:04:47.665673 coreos-metadata[1491]: Sep 05 00:04:47.661 INFO Fetch successful Sep 5 00:04:47.665673 coreos-metadata[1491]: Sep 05 00:04:47.664 INFO Fetching http://169.254.169.254/hetzner/v1/metadata/private-networks: Attempt #1 Sep 5 00:04:47.662517 systemd[1]: Starting update-engine.service - Update Engine... Sep 5 00:04:47.667534 coreos-metadata[1491]: Sep 05 00:04:47.666 INFO Fetch successful Sep 5 00:04:47.674638 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 5 00:04:47.682836 extend-filesystems[1496]: Found loop4 Sep 5 00:04:47.685107 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 5 00:04:47.685341 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 5 00:04:47.692511 extend-filesystems[1496]: Found loop5 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found loop6 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found loop7 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found sda Sep 5 00:04:47.692511 extend-filesystems[1496]: Found sda1 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found sda2 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found sda3 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found usr Sep 5 00:04:47.692511 extend-filesystems[1496]: Found sda4 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found sda6 Sep 5 00:04:47.692511 extend-filesystems[1496]: Found sda7 Sep 5 00:04:47.747385 extend-filesystems[1496]: Found sda9 Sep 5 00:04:47.747385 extend-filesystems[1496]: Checking size of /dev/sda9 Sep 5 00:04:47.735156 dbus-daemon[1492]: [system] SELinux support is enabled Sep 5 00:04:47.761265 jq[1505]: true Sep 5 00:04:47.761388 update_engine[1504]: I20260905 00:04:47.721153 1504 main.cc:92] Flatcar Update Engine starting Sep 5 00:04:47.761388 update_engine[1504]: I20260905 00:04:47.747815 1504 update_check_scheduler.cc:74] Next update check in 4m24s Sep 5 00:04:47.708856 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 5 00:04:47.774157 extend-filesystems[1496]: Resized partition /dev/sda9 Sep 5 00:04:47.709379 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 5 00:04:47.735408 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 5 00:04:47.780799 jq[1520]: true Sep 5 00:04:47.741529 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 5 00:04:47.783888 extend-filesystems[1530]: resize2fs 1.47.1 (20-May-2024) Sep 5 00:04:47.795369 kernel: EXT4-fs (sda9): resizing filesystem from 1617920 to 19393531 blocks Sep 5 00:04:47.741560 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 5 00:04:47.742166 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 5 00:04:47.742179 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 5 00:04:47.747744 systemd[1]: Started update-engine.service - Update Engine. Sep 5 00:04:47.758305 (ntainerd)[1526]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 5 00:04:47.759017 systemd[1]: motdgen.service: Deactivated successfully. Sep 5 00:04:47.759267 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 5 00:04:47.781611 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 5 00:04:47.839254 systemd-logind[1501]: New seat seat0. Sep 5 00:04:47.846321 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 5 00:04:47.851198 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 5 00:04:47.858739 systemd-logind[1501]: Watching system buttons on /dev/input/event2 (Power Button) Sep 5 00:04:47.858766 systemd-logind[1501]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 5 00:04:47.860441 systemd[1]: Started systemd-logind.service - User Login Management. Sep 5 00:04:47.940970 bash[1560]: Updated "/home/core/.ssh/authorized_keys" Sep 5 00:04:47.942514 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 5 00:04:47.954672 systemd[1]: Starting sshkeys.service... Sep 5 00:04:47.974475 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 31 scanned by (udev-worker) (1340) Sep 5 00:04:48.001650 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Sep 5 00:04:48.010748 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Sep 5 00:04:48.055009 coreos-metadata[1566]: Sep 05 00:04:48.054 INFO Fetching http://169.254.169.254/hetzner/v1/metadata/public-keys: Attempt #1 Sep 5 00:04:48.057480 coreos-metadata[1566]: Sep 05 00:04:48.056 INFO Fetch successful Sep 5 00:04:48.057546 containerd[1526]: time="2026-09-05T00:04:48.057154533Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Sep 5 00:04:48.062519 kernel: EXT4-fs (sda9): resized filesystem to 19393531 Sep 5 00:04:48.078335 unknown[1566]: wrote ssh authorized keys file for user: core Sep 5 00:04:48.080257 extend-filesystems[1530]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Sep 5 00:04:48.080257 extend-filesystems[1530]: old_desc_blocks = 1, new_desc_blocks = 10 Sep 5 00:04:48.080257 extend-filesystems[1530]: The filesystem on /dev/sda9 is now 19393531 (4k) blocks long. Sep 5 00:04:48.088425 extend-filesystems[1496]: Resized filesystem in /dev/sda9 Sep 5 00:04:48.088425 extend-filesystems[1496]: Found sr0 Sep 5 00:04:48.087032 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 5 00:04:48.092145 containerd[1526]: time="2026-09-05T00:04:48.081541794Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:04:48.087322 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092477458Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.156-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092513358Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092529548Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092681118Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092692248Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092738438Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092746998Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092891008Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092901538Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092910548Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093473 containerd[1526]: time="2026-09-05T00:04:48.092917898Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093650 containerd[1526]: time="2026-09-05T00:04:48.092985138Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093650 containerd[1526]: time="2026-09-05T00:04:48.093190898Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093650 containerd[1526]: time="2026-09-05T00:04:48.093293449Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 5 00:04:48.093650 containerd[1526]: time="2026-09-05T00:04:48.093303059Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 5 00:04:48.093650 containerd[1526]: time="2026-09-05T00:04:48.093371109Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 5 00:04:48.093650 containerd[1526]: time="2026-09-05T00:04:48.093407949Z" level=info msg="metadata content store policy set" policy=shared Sep 5 00:04:48.095924 locksmithd[1532]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097490480Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097530640Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097544390Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097556860Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097567930Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097688730Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097833210Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097915560Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097925520Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097934440Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097943700Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097952580Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097961590Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098765 containerd[1526]: time="2026-09-05T00:04:48.097971210Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.097984830Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098006860Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098015860Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098023830Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098038800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098049261Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098058001Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098068101Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098078161Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098088401Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098097281Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098106661Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098118271Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.098985 containerd[1526]: time="2026-09-05T00:04:48.098135911Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098146791Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098158561Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098174061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098188851Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098207671Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098219791Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098233621Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098264041Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098276871Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098284201Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098292091Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098299721Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098308241Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 5 00:04:48.099172 containerd[1526]: time="2026-09-05T00:04:48.098316381Z" level=info msg="NRI interface is disabled by configuration." Sep 5 00:04:48.099388 containerd[1526]: time="2026-09-05T00:04:48.098323621Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 5 00:04:48.101898 containerd[1526]: time="2026-09-05T00:04:48.101346792Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Sep 5 00:04:48.101898 containerd[1526]: time="2026-09-05T00:04:48.101399292Z" level=info msg="Connect containerd service" Sep 5 00:04:48.101898 containerd[1526]: time="2026-09-05T00:04:48.101438382Z" level=info msg="using legacy CRI server" Sep 5 00:04:48.101898 containerd[1526]: time="2026-09-05T00:04:48.101445442Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 5 00:04:48.101898 containerd[1526]: time="2026-09-05T00:04:48.101543522Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102422992Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102691892Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102737562Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102785342Z" level=info msg="Start subscribing containerd event" Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102817102Z" level=info msg="Start recovering state" Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102863543Z" level=info msg="Start event monitor" Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102879303Z" level=info msg="Start snapshots syncer" Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102885613Z" level=info msg="Start cni network conf syncer for default" Sep 5 00:04:48.104197 containerd[1526]: time="2026-09-05T00:04:48.102891433Z" level=info msg="Start streaming server" Sep 5 00:04:48.103006 systemd[1]: Started containerd.service - containerd container runtime. Sep 5 00:04:48.108973 containerd[1526]: time="2026-09-05T00:04:48.108923535Z" level=info msg="containerd successfully booted in 0.053831s" Sep 5 00:04:48.118524 update-ssh-keys[1576]: Updated "/home/core/.ssh/authorized_keys" Sep 5 00:04:48.119386 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Sep 5 00:04:48.122754 systemd[1]: Finished sshkeys.service. Sep 5 00:04:48.139410 sshd_keygen[1523]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 5 00:04:48.159753 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 5 00:04:48.176872 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 5 00:04:48.183947 systemd[1]: issuegen.service: Deactivated successfully. Sep 5 00:04:48.184167 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 5 00:04:48.190720 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 5 00:04:48.202604 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 5 00:04:48.209863 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 5 00:04:48.217735 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 5 00:04:48.218309 systemd[1]: Reached target getty.target - Login Prompts. Sep 5 00:04:48.976854 systemd-networkd[1411]: eth1: Gained IPv6LL Sep 5 00:04:48.977685 systemd-timesyncd[1464]: Network configuration changed, trying to establish connection. Sep 5 00:04:48.980653 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 5 00:04:48.982108 systemd[1]: Reached target network-online.target - Network is Online. Sep 5 00:04:48.990170 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 5 00:04:48.996564 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 5 00:04:49.018305 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 5 00:04:49.616601 systemd-networkd[1411]: eth0: Gained IPv6LL Sep 5 00:04:49.618028 systemd-timesyncd[1464]: Network configuration changed, trying to establish connection. Sep 5 00:04:49.750217 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:04:49.752744 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 5 00:04:49.754148 (kubelet)[1615]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 5 00:04:49.755972 systemd[1]: Startup finished in 1.519s (kernel) + 5.325s (initrd) + 4.751s (userspace) = 11.596s. Sep 5 00:04:50.202842 kubelet[1615]: E0905 00:04:50.202792 1615 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Sep 5 00:04:50.205923 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 5 00:04:50.206113 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 5 00:04:53.612288 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 5 00:04:53.618959 systemd[1]: Started sshd@0-37.27.36.105:22-20.76.204.135:39780.service - OpenSSH per-connection server daemon (20.76.204.135:39780). Sep 5 00:04:53.861404 sshd[1627]: Accepted publickey for core from 20.76.204.135 port 39780 ssh2: RSA SHA256:CqbEW7A0nBFbl9s1g4zAamkUoAlw7g9Zz/gKAa68A8g Sep 5 00:04:53.863362 sshd[1627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:04:53.876978 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 5 00:04:53.883168 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 5 00:04:53.886852 systemd-logind[1501]: New session 1 of user core. Sep 5 00:04:53.898026 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 5 00:04:53.903879 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 5 00:04:53.929064 (systemd)[1631]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 5 00:04:54.024167 systemd[1631]: Queued start job for default target default.target. Sep 5 00:04:54.033598 systemd[1631]: Created slice app.slice - User Application Slice. Sep 5 00:04:54.033622 systemd[1631]: Reached target paths.target - Paths. Sep 5 00:04:54.033633 systemd[1631]: Reached target timers.target - Timers. Sep 5 00:04:54.037525 systemd[1631]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 5 00:04:54.046399 systemd[1631]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 5 00:04:54.046470 systemd[1631]: Reached target sockets.target - Sockets. Sep 5 00:04:54.046483 systemd[1631]: Reached target basic.target - Basic System. Sep 5 00:04:54.046519 systemd[1631]: Reached target default.target - Main User Target. Sep 5 00:04:54.046551 systemd[1631]: Startup finished in 110ms. Sep 5 00:04:54.046628 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 5 00:04:54.047794 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 5 00:04:54.237904 systemd[1]: Started sshd@1-37.27.36.105:22-20.76.204.135:39786.service - OpenSSH per-connection server daemon (20.76.204.135:39786). Sep 5 00:04:54.477549 sshd[1642]: Accepted publickey for core from 20.76.204.135 port 39786 ssh2: RSA SHA256:CqbEW7A0nBFbl9s1g4zAamkUoAlw7g9Zz/gKAa68A8g Sep 5 00:04:54.480811 sshd[1642]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:04:54.487518 systemd-logind[1501]: New session 2 of user core. Sep 5 00:04:54.494761 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 5 00:04:54.664591 sshd[1642]: pam_unix(sshd:session): session closed for user core Sep 5 00:04:54.670906 systemd[1]: sshd@1-37.27.36.105:22-20.76.204.135:39786.service: Deactivated successfully. Sep 5 00:04:54.674528 systemd[1]: session-2.scope: Deactivated successfully. Sep 5 00:04:54.675616 systemd-logind[1501]: Session 2 logged out. Waiting for processes to exit. Sep 5 00:04:54.677241 systemd-logind[1501]: Removed session 2. Sep 5 00:04:54.710249 systemd[1]: Started sshd@2-37.27.36.105:22-20.76.204.135:39790.service - OpenSSH per-connection server daemon (20.76.204.135:39790). Sep 5 00:04:54.932820 sshd[1649]: Accepted publickey for core from 20.76.204.135 port 39790 ssh2: RSA SHA256:CqbEW7A0nBFbl9s1g4zAamkUoAlw7g9Zz/gKAa68A8g Sep 5 00:04:54.934810 sshd[1649]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:04:54.939403 systemd-logind[1501]: New session 3 of user core. Sep 5 00:04:54.948609 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 5 00:04:55.099392 sshd[1649]: pam_unix(sshd:session): session closed for user core Sep 5 00:04:55.105075 systemd[1]: sshd@2-37.27.36.105:22-20.76.204.135:39790.service: Deactivated successfully. Sep 5 00:04:55.109050 systemd[1]: session-3.scope: Deactivated successfully. Sep 5 00:04:55.111552 systemd-logind[1501]: Session 3 logged out. Waiting for processes to exit. Sep 5 00:04:55.114182 systemd-logind[1501]: Removed session 3. Sep 5 00:04:55.144568 systemd[1]: Started sshd@3-37.27.36.105:22-20.76.204.135:39792.service - OpenSSH per-connection server daemon (20.76.204.135:39792). Sep 5 00:04:55.388436 sshd[1656]: Accepted publickey for core from 20.76.204.135 port 39792 ssh2: RSA SHA256:CqbEW7A0nBFbl9s1g4zAamkUoAlw7g9Zz/gKAa68A8g Sep 5 00:04:55.391170 sshd[1656]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:04:55.398195 systemd-logind[1501]: New session 4 of user core. Sep 5 00:04:55.405708 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 5 00:04:55.573881 sshd[1656]: pam_unix(sshd:session): session closed for user core Sep 5 00:04:55.578588 systemd[1]: sshd@3-37.27.36.105:22-20.76.204.135:39792.service: Deactivated successfully. Sep 5 00:04:55.582192 systemd[1]: session-4.scope: Deactivated successfully. Sep 5 00:04:55.584925 systemd-logind[1501]: Session 4 logged out. Waiting for processes to exit. Sep 5 00:04:55.587064 systemd-logind[1501]: Removed session 4. Sep 5 00:04:55.624842 systemd[1]: Started sshd@4-37.27.36.105:22-20.76.204.135:58522.service - OpenSSH per-connection server daemon (20.76.204.135:58522). Sep 5 00:04:55.860037 sshd[1663]: Accepted publickey for core from 20.76.204.135 port 58522 ssh2: RSA SHA256:CqbEW7A0nBFbl9s1g4zAamkUoAlw7g9Zz/gKAa68A8g Sep 5 00:04:55.862872 sshd[1663]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 00:04:55.870645 systemd-logind[1501]: New session 5 of user core. Sep 5 00:04:55.876691 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 5 00:04:56.019727 sudo[1666]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 5 00:04:56.020634 sudo[1666]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 00:04:56.572999 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:04:56.588604 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 5 00:04:56.623650 systemd[1]: Reloading requested from client PID 1699 ('systemctl') (unit session-5.scope)... Sep 5 00:04:56.623822 systemd[1]: Reloading... Sep 5 00:04:56.728502 zram_generator::config[1741]: No configuration found. Sep 5 00:04:56.813819 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 5 00:04:56.874556 systemd[1]: Reloading finished in 250 ms. Sep 5 00:04:56.928115 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:04:56.931948 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Sep 5 00:04:56.933639 systemd[1]: kubelet.service: Deactivated successfully. Sep 5 00:04:56.933900 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:04:56.940782 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 5 00:04:57.077335 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 5 00:04:57.083624 (kubelet)[1794]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 5 00:04:57.131433 kubelet[1794]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 5 00:04:57.131433 kubelet[1794]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 5 00:04:57.131899 kubelet[1794]: I0905 00:04:57.131841 1794 server.go:213] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 5 00:04:57.467381 kubelet[1794]: I0905 00:04:57.467193 1794 server.go:529] "Kubelet version" kubeletVersion="v1.34.4" Sep 5 00:04:57.467381 kubelet[1794]: I0905 00:04:57.467219 1794 server.go:531] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 5 00:04:57.469204 kubelet[1794]: I0905 00:04:57.469156 1794 watchdog_linux.go:95] "Systemd watchdog is not enabled" Sep 5 00:04:57.469204 kubelet[1794]: I0905 00:04:57.469171 1794 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 5 00:04:57.469442 kubelet[1794]: I0905 00:04:57.469348 1794 server.go:956] "Client rotation is on, will bootstrap in background" Sep 5 00:04:57.474938 kubelet[1794]: I0905 00:04:57.474549 1794 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 5 00:04:57.478848 kubelet[1794]: E0905 00:04:57.478750 1794 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Sep 5 00:04:57.478848 kubelet[1794]: I0905 00:04:57.478823 1794 server.go:1400] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Sep 5 00:04:57.482640 kubelet[1794]: I0905 00:04:57.482596 1794 server.go:781] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Sep 5 00:04:57.483378 kubelet[1794]: I0905 00:04:57.483293 1794 container_manager_linux.go:270] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 5 00:04:57.483506 kubelet[1794]: I0905 00:04:57.483334 1794 container_manager_linux.go:275] "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":"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},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 5 00:04:57.483506 kubelet[1794]: I0905 00:04:57.483499 1794 topology_manager.go:138] "Creating topology manager with none policy" Sep 5 00:04:57.483506 kubelet[1794]: I0905 00:04:57.483507 1794 container_manager_linux.go:306] "Creating device plugin manager" Sep 5 00:04:57.483760 kubelet[1794]: I0905 00:04:57.483572 1794 container_manager_linux.go:315] "Creating Dynamic Resource Allocation (DRA) manager" Sep 5 00:04:57.490224 kubelet[1794]: I0905 00:04:57.488619 1794 state_mem.go:36] "Initialized new in-memory state store" Sep 5 00:04:57.490224 kubelet[1794]: I0905 00:04:57.488772 1794 kubelet.go:475] "Attempting to sync node with API server" Sep 5 00:04:57.490224 kubelet[1794]: I0905 00:04:57.488811 1794 kubelet.go:376] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 5 00:04:57.490224 kubelet[1794]: I0905 00:04:57.488829 1794 kubelet.go:387] "Adding apiserver pod source" Sep 5 00:04:57.490224 kubelet[1794]: I0905 00:04:57.488842 1794 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 5 00:04:57.490224 kubelet[1794]: E0905 00:04:57.490075 1794 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:04:57.490224 kubelet[1794]: E0905 00:04:57.490156 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:04:57.491436 kubelet[1794]: I0905 00:04:57.491392 1794 kuberuntime_manager.go:291] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Sep 5 00:04:57.491771 kubelet[1794]: I0905 00:04:57.491739 1794 kubelet.go:940] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 5 00:04:57.491771 kubelet[1794]: I0905 00:04:57.491762 1794 kubelet.go:964] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Sep 5 00:04:57.491978 kubelet[1794]: W0905 00:04:57.491804 1794 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 5 00:04:57.494255 kubelet[1794]: I0905 00:04:57.494222 1794 server.go:1262] "Started kubelet" Sep 5 00:04:57.496186 kubelet[1794]: I0905 00:04:57.496140 1794 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Sep 5 00:04:57.497071 kubelet[1794]: I0905 00:04:57.497015 1794 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 5 00:04:57.497246 kubelet[1794]: I0905 00:04:57.497082 1794 server_v1.go:49] "podresources" method="list" useActivePods=true Sep 5 00:04:57.497361 kubelet[1794]: I0905 00:04:57.497330 1794 server.go:249] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 5 00:04:57.499049 kubelet[1794]: I0905 00:04:57.498999 1794 server.go:310] "Adding debug handlers to kubelet server" Sep 5 00:04:57.500712 kubelet[1794]: E0905 00:04:57.500672 1794 reflector.go:205] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.4\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Sep 5 00:04:57.500832 kubelet[1794]: E0905 00:04:57.500771 1794 reflector.go:205] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Sep 5 00:04:57.503262 kubelet[1794]: I0905 00:04:57.503226 1794 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 5 00:04:57.505259 kubelet[1794]: E0905 00:04:57.503204 1794 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.4.18d243ef653c5846 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.4,UID:10.0.0.4,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.4,},FirstTimestamp:2026-09-05 00:04:57.494206534 +0000 UTC m=+0.407092501,LastTimestamp:2026-09-05 00:04:57.494206534 +0000 UTC m=+0.407092501,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.4,}" Sep 5 00:04:57.505259 kubelet[1794]: I0905 00:04:57.504735 1794 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 5 00:04:57.511435 kubelet[1794]: E0905 00:04:57.510233 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:57.511435 kubelet[1794]: I0905 00:04:57.510248 1794 volume_manager.go:313] "Starting Kubelet Volume Manager" Sep 5 00:04:57.511435 kubelet[1794]: I0905 00:04:57.510321 1794 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Sep 5 00:04:57.511435 kubelet[1794]: I0905 00:04:57.510356 1794 reconciler.go:29] "Reconciler: start to sync state" Sep 5 00:04:57.511435 kubelet[1794]: I0905 00:04:57.510826 1794 factory.go:223] Registration of the systemd container factory successfully Sep 5 00:04:57.511435 kubelet[1794]: I0905 00:04:57.510875 1794 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 5 00:04:57.511812 kubelet[1794]: E0905 00:04:57.511750 1794 kubelet.go:1615] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 5 00:04:57.513651 kubelet[1794]: I0905 00:04:57.512481 1794 factory.go:223] Registration of the containerd container factory successfully Sep 5 00:04:57.527809 kubelet[1794]: E0905 00:04:57.527764 1794 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" Sep 5 00:04:57.530866 kubelet[1794]: I0905 00:04:57.530830 1794 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 5 00:04:57.531054 kubelet[1794]: I0905 00:04:57.531032 1794 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 5 00:04:57.531187 kubelet[1794]: I0905 00:04:57.531170 1794 state_mem.go:36] "Initialized new in-memory state store" Sep 5 00:04:57.535421 kubelet[1794]: I0905 00:04:57.535393 1794 policy_none.go:49] "None policy: Start" Sep 5 00:04:57.535615 kubelet[1794]: I0905 00:04:57.535600 1794 memory_manager.go:187] "Starting memorymanager" policy="None" Sep 5 00:04:57.535736 kubelet[1794]: I0905 00:04:57.535719 1794 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Sep 5 00:04:57.539503 kubelet[1794]: I0905 00:04:57.538528 1794 policy_none.go:47] "Start" Sep 5 00:04:57.548089 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 5 00:04:57.560014 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 5 00:04:57.564094 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 5 00:04:57.573480 kubelet[1794]: E0905 00:04:57.573443 1794 manager.go:513] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 5 00:04:57.574584 kubelet[1794]: I0905 00:04:57.574134 1794 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 5 00:04:57.574584 kubelet[1794]: I0905 00:04:57.574146 1794 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 5 00:04:57.574584 kubelet[1794]: I0905 00:04:57.574438 1794 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 5 00:04:57.579038 kubelet[1794]: E0905 00:04:57.578908 1794 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 5 00:04:57.579089 kubelet[1794]: E0905 00:04:57.579047 1794 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.4\" not found" Sep 5 00:04:57.593782 kubelet[1794]: I0905 00:04:57.593729 1794 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Sep 5 00:04:57.595954 kubelet[1794]: I0905 00:04:57.595683 1794 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Sep 5 00:04:57.595954 kubelet[1794]: I0905 00:04:57.595701 1794 status_manager.go:244] "Starting to sync pod status with apiserver" Sep 5 00:04:57.595954 kubelet[1794]: I0905 00:04:57.595722 1794 kubelet.go:2428] "Starting kubelet main sync loop" Sep 5 00:04:57.595954 kubelet[1794]: E0905 00:04:57.595756 1794 kubelet.go:2452] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 5 00:04:57.603060 sudo[1666]: pam_unix(sudo:session): session closed for user root Sep 5 00:04:57.635120 sshd[1663]: pam_unix(sshd:session): session closed for user core Sep 5 00:04:57.639559 systemd[1]: sshd@4-37.27.36.105:22-20.76.204.135:58522.service: Deactivated successfully. Sep 5 00:04:57.641279 systemd[1]: session-5.scope: Deactivated successfully. Sep 5 00:04:57.642136 systemd-logind[1501]: Session 5 logged out. Waiting for processes to exit. Sep 5 00:04:57.643128 systemd-logind[1501]: Removed session 5. Sep 5 00:04:57.678183 kubelet[1794]: I0905 00:04:57.678118 1794 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.4" Sep 5 00:04:57.685308 kubelet[1794]: I0905 00:04:57.685075 1794 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.4" Sep 5 00:04:57.685308 kubelet[1794]: E0905 00:04:57.685118 1794 kubelet_node_status.go:486] "Error updating node status, will retry" err="error getting node \"10.0.0.4\": node \"10.0.0.4\" not found" Sep 5 00:04:57.699800 kubelet[1794]: E0905 00:04:57.699748 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:57.800694 kubelet[1794]: E0905 00:04:57.800544 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:57.901636 kubelet[1794]: E0905 00:04:57.901551 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.002420 kubelet[1794]: E0905 00:04:58.002354 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.103283 kubelet[1794]: E0905 00:04:58.103219 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.204373 kubelet[1794]: E0905 00:04:58.204192 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.305187 kubelet[1794]: E0905 00:04:58.305080 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.406159 kubelet[1794]: E0905 00:04:58.405967 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.470793 kubelet[1794]: I0905 00:04:58.470729 1794 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 5 00:04:58.471022 kubelet[1794]: I0905 00:04:58.470913 1794 reflector.go:571] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Sep 5 00:04:58.471022 kubelet[1794]: I0905 00:04:58.470944 1794 reflector.go:571] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Sep 5 00:04:58.490249 kubelet[1794]: E0905 00:04:58.490213 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:04:58.506866 kubelet[1794]: E0905 00:04:58.506694 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.607845 kubelet[1794]: E0905 00:04:58.607812 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.708926 kubelet[1794]: E0905 00:04:58.708712 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.809596 kubelet[1794]: E0905 00:04:58.809545 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:58.910319 kubelet[1794]: E0905 00:04:58.910250 1794 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.0.0.4\" not found" Sep 5 00:04:59.011201 kubelet[1794]: I0905 00:04:59.011026 1794 kuberuntime_manager.go:1828] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 5 00:04:59.011421 containerd[1526]: time="2026-09-05T00:04:59.011356966Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 5 00:04:59.012016 kubelet[1794]: I0905 00:04:59.011531 1794 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 5 00:04:59.490965 kubelet[1794]: I0905 00:04:59.490868 1794 apiserver.go:52] "Watching apiserver" Sep 5 00:04:59.490965 kubelet[1794]: E0905 00:04:59.490904 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:04:59.511301 kubelet[1794]: I0905 00:04:59.511244 1794 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Sep 5 00:04:59.513764 systemd[1]: Created slice kubepods-burstable-pode7ea747e_6a04_4695_aef3_eb206f359cd0.slice - libcontainer container kubepods-burstable-pode7ea747e_6a04_4695_aef3_eb206f359cd0.slice. Sep 5 00:04:59.524635 kubelet[1794]: I0905 00:04:59.523546 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/e7ea747e-6a04-4695-aef3-eb206f359cd0-run\") pod \"kube-flannel-ds-cjlkt\" (UID: \"e7ea747e-6a04-4695-aef3-eb206f359cd0\") " pod="kube-flannel/kube-flannel-ds-cjlkt" Sep 5 00:04:59.524635 kubelet[1794]: I0905 00:04:59.523590 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/e7ea747e-6a04-4695-aef3-eb206f359cd0-cni\") pod \"kube-flannel-ds-cjlkt\" (UID: \"e7ea747e-6a04-4695-aef3-eb206f359cd0\") " pod="kube-flannel/kube-flannel-ds-cjlkt" Sep 5 00:04:59.524635 kubelet[1794]: I0905 00:04:59.523615 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/e7ea747e-6a04-4695-aef3-eb206f359cd0-flannel-cfg\") pod \"kube-flannel-ds-cjlkt\" (UID: \"e7ea747e-6a04-4695-aef3-eb206f359cd0\") " pod="kube-flannel/kube-flannel-ds-cjlkt" Sep 5 00:04:59.524635 kubelet[1794]: I0905 00:04:59.523639 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/e7ea747e-6a04-4695-aef3-eb206f359cd0-xtables-lock\") pod \"kube-flannel-ds-cjlkt\" (UID: \"e7ea747e-6a04-4695-aef3-eb206f359cd0\") " pod="kube-flannel/kube-flannel-ds-cjlkt" Sep 5 00:04:59.524635 kubelet[1794]: I0905 00:04:59.523673 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ksktm\" (UniqueName: \"kubernetes.io/projected/3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf-kube-api-access-ksktm\") pod \"kube-proxy-jktz9\" (UID: \"3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf\") " pod="kube-system/kube-proxy-jktz9" Sep 5 00:04:59.524897 kubelet[1794]: I0905 00:04:59.523706 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/e7ea747e-6a04-4695-aef3-eb206f359cd0-cni-plugin\") pod \"kube-flannel-ds-cjlkt\" (UID: \"e7ea747e-6a04-4695-aef3-eb206f359cd0\") " pod="kube-flannel/kube-flannel-ds-cjlkt" Sep 5 00:04:59.524897 kubelet[1794]: I0905 00:04:59.523742 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-t8gr5\" (UniqueName: \"kubernetes.io/projected/e7ea747e-6a04-4695-aef3-eb206f359cd0-kube-api-access-t8gr5\") pod \"kube-flannel-ds-cjlkt\" (UID: \"e7ea747e-6a04-4695-aef3-eb206f359cd0\") " pod="kube-flannel/kube-flannel-ds-cjlkt" Sep 5 00:04:59.524897 kubelet[1794]: I0905 00:04:59.523763 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf-kube-proxy\") pod \"kube-proxy-jktz9\" (UID: \"3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf\") " pod="kube-system/kube-proxy-jktz9" Sep 5 00:04:59.524897 kubelet[1794]: I0905 00:04:59.523786 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf-xtables-lock\") pod \"kube-proxy-jktz9\" (UID: \"3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf\") " pod="kube-system/kube-proxy-jktz9" Sep 5 00:04:59.524897 kubelet[1794]: I0905 00:04:59.523807 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf-lib-modules\") pod \"kube-proxy-jktz9\" (UID: \"3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf\") " pod="kube-system/kube-proxy-jktz9" Sep 5 00:04:59.528310 systemd[1]: Created slice kubepods-besteffort-pod3b0ca73a_d39b_4e5a_bd92_bdd9d3eabdaf.slice - libcontainer container kubepods-besteffort-pod3b0ca73a_d39b_4e5a_bd92_bdd9d3eabdaf.slice. Sep 5 00:04:59.830085 containerd[1526]: time="2026-09-05T00:04:59.829211676Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-cjlkt,Uid:e7ea747e-6a04-4695-aef3-eb206f359cd0,Namespace:kube-flannel,Attempt:0,}" Sep 5 00:04:59.842259 containerd[1526]: time="2026-09-05T00:04:59.842201262Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-jktz9,Uid:3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf,Namespace:kube-system,Attempt:0,}" Sep 5 00:05:00.232136 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3715871872.mount: Deactivated successfully. Sep 5 00:05:00.241473 containerd[1526]: time="2026-09-05T00:05:00.241378358Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:05:00.243193 containerd[1526]: time="2026-09-05T00:05:00.242884759Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:05:00.244879 containerd[1526]: time="2026-09-05T00:05:00.244587789Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 5 00:05:00.245936 containerd[1526]: time="2026-09-05T00:05:00.245891300Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312118" Sep 5 00:05:00.248466 containerd[1526]: time="2026-09-05T00:05:00.247155330Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:05:00.252935 containerd[1526]: time="2026-09-05T00:05:00.252909963Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 5 00:05:00.254071 containerd[1526]: time="2026-09-05T00:05:00.254027673Z" 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 424.681987ms" Sep 5 00:05:00.255296 containerd[1526]: time="2026-09-05T00:05:00.255257734Z" 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 412.977632ms" Sep 5 00:05:00.357897 containerd[1526]: time="2026-09-05T00:05:00.357644496Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 5 00:05:00.357897 containerd[1526]: time="2026-09-05T00:05:00.357679586Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 5 00:05:00.357897 containerd[1526]: time="2026-09-05T00:05:00.357693766Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:00.357897 containerd[1526]: time="2026-09-05T00:05:00.357765457Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:00.365124 containerd[1526]: time="2026-09-05T00:05:00.364921130Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 5 00:05:00.365124 containerd[1526]: time="2026-09-05T00:05:00.364974240Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 5 00:05:00.365124 containerd[1526]: time="2026-09-05T00:05:00.364984470Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:00.365124 containerd[1526]: time="2026-09-05T00:05:00.365119110Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:00.417562 systemd[1]: Started cri-containerd-4c04a3e46f5cbfcaf3e317454388d844db4f24ba9ecc3fb99ac327bd2173125f.scope - libcontainer container 4c04a3e46f5cbfcaf3e317454388d844db4f24ba9ecc3fb99ac327bd2173125f. Sep 5 00:05:00.419444 systemd[1]: Started cri-containerd-f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5.scope - libcontainer container f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5. Sep 5 00:05:00.442584 containerd[1526]: time="2026-09-05T00:05:00.442500222Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-jktz9,Uid:3b0ca73a-d39b-4e5a-bd92-bdd9d3eabdaf,Namespace:kube-system,Attempt:0,} returns sandbox id \"4c04a3e46f5cbfcaf3e317454388d844db4f24ba9ecc3fb99ac327bd2173125f\"" Sep 5 00:05:00.444751 containerd[1526]: time="2026-09-05T00:05:00.444582323Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.11\"" Sep 5 00:05:00.453200 containerd[1526]: time="2026-09-05T00:05:00.453181106Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-cjlkt,Uid:e7ea747e-6a04-4695-aef3-eb206f359cd0,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5\"" Sep 5 00:05:00.491365 kubelet[1794]: E0905 00:05:00.491204 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:01.153445 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2904168551.mount: Deactivated successfully. Sep 5 00:05:01.360193 containerd[1526]: time="2026-09-05T00:05:01.360152564Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.34.11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:01.361049 containerd[1526]: time="2026-09-05T00:05:01.361001784Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.34.11: active requests=0, bytes read=27443430" Sep 5 00:05:01.361858 containerd[1526]: time="2026-09-05T00:05:01.361758805Z" level=info msg="ImageCreate event name:\"sha256:09f56957dedd922eb9fd2239b1ef918cc5faeaafe4814e124bf33a48c7ba5248\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:01.363158 containerd[1526]: time="2026-09-05T00:05:01.363135635Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:ab8219b43a5ddbafad58bf901509c29994e79fcd7e3b6c47f67f0af790328d89\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:01.363589 containerd[1526]: time="2026-09-05T00:05:01.363566625Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.34.11\" with image id \"sha256:09f56957dedd922eb9fd2239b1ef918cc5faeaafe4814e124bf33a48c7ba5248\", repo tag \"registry.k8s.io/kube-proxy:v1.34.11\", repo digest \"registry.k8s.io/kube-proxy@sha256:ab8219b43a5ddbafad58bf901509c29994e79fcd7e3b6c47f67f0af790328d89\", size \"27442398\" in 918.964792ms" Sep 5 00:05:01.363621 containerd[1526]: time="2026-09-05T00:05:01.363592575Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.11\" returns image reference \"sha256:09f56957dedd922eb9fd2239b1ef918cc5faeaafe4814e124bf33a48c7ba5248\"" Sep 5 00:05:01.365048 containerd[1526]: time="2026-09-05T00:05:01.364921906Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 5 00:05:01.367153 containerd[1526]: time="2026-09-05T00:05:01.367065797Z" level=info msg="CreateContainer within sandbox \"4c04a3e46f5cbfcaf3e317454388d844db4f24ba9ecc3fb99ac327bd2173125f\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 5 00:05:01.385708 containerd[1526]: time="2026-09-05T00:05:01.385678475Z" level=info msg="CreateContainer within sandbox \"4c04a3e46f5cbfcaf3e317454388d844db4f24ba9ecc3fb99ac327bd2173125f\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"89c088b0fe08754f6b931b04d59689e3a945d0581341118d0b312592965f1e26\"" Sep 5 00:05:01.386174 containerd[1526]: time="2026-09-05T00:05:01.386151325Z" level=info msg="StartContainer for \"89c088b0fe08754f6b931b04d59689e3a945d0581341118d0b312592965f1e26\"" Sep 5 00:05:01.410561 systemd[1]: Started cri-containerd-89c088b0fe08754f6b931b04d59689e3a945d0581341118d0b312592965f1e26.scope - libcontainer container 89c088b0fe08754f6b931b04d59689e3a945d0581341118d0b312592965f1e26. Sep 5 00:05:01.437178 containerd[1526]: time="2026-09-05T00:05:01.437152126Z" level=info msg="StartContainer for \"89c088b0fe08754f6b931b04d59689e3a945d0581341118d0b312592965f1e26\" returns successfully" Sep 5 00:05:01.492161 kubelet[1794]: E0905 00:05:01.492127 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:02.493293 kubelet[1794]: E0905 00:05:02.493189 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:03.419545 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1178712538.mount: Deactivated successfully. Sep 5 00:05:03.455236 containerd[1526]: time="2026-09-05T00:05:03.455189667Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:03.456101 containerd[1526]: time="2026-09-05T00:05:03.456042077Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Sep 5 00:05:03.456925 containerd[1526]: time="2026-09-05T00:05:03.456800737Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:03.459625 containerd[1526]: time="2026-09-05T00:05:03.459350278Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:03.460656 containerd[1526]: time="2026-09-05T00:05:03.460080319Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 2.095140693s" Sep 5 00:05:03.460656 containerd[1526]: time="2026-09-05T00:05:03.460102839Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Sep 5 00:05:03.463252 containerd[1526]: time="2026-09-05T00:05:03.463231220Z" level=info msg="CreateContainer within sandbox \"f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 5 00:05:03.472378 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2932050854.mount: Deactivated successfully. Sep 5 00:05:03.485308 containerd[1526]: time="2026-09-05T00:05:03.485273779Z" level=info msg="CreateContainer within sandbox \"f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6\"" Sep 5 00:05:03.485683 containerd[1526]: time="2026-09-05T00:05:03.485657269Z" level=info msg="StartContainer for \"3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6\"" Sep 5 00:05:03.494527 kubelet[1794]: E0905 00:05:03.494496 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:03.510556 systemd[1]: Started cri-containerd-3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6.scope - libcontainer container 3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6. Sep 5 00:05:03.529864 containerd[1526]: time="2026-09-05T00:05:03.529819278Z" level=info msg="StartContainer for \"3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6\" returns successfully" Sep 5 00:05:03.530615 systemd[1]: cri-containerd-3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6.scope: Deactivated successfully. Sep 5 00:05:03.628896 kubelet[1794]: I0905 00:05:03.628799 1794 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-jktz9" podStartSLOduration=4.708578476 podStartE2EDuration="5.628780919s" podCreationTimestamp="2026-09-05 00:04:58 +0000 UTC" firstStartedPulling="2026-09-05 00:05:00.444210973 +0000 UTC m=+3.357096950" lastFinishedPulling="2026-09-05 00:05:01.364413426 +0000 UTC m=+4.277299393" observedRunningTime="2026-09-05 00:05:01.621087393 +0000 UTC m=+4.533973410" watchObservedRunningTime="2026-09-05 00:05:03.628780919 +0000 UTC m=+6.541666916" Sep 5 00:05:03.680429 containerd[1526]: time="2026-09-05T00:05:03.680102600Z" level=info msg="shim disconnected" id=3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6 namespace=k8s.io Sep 5 00:05:03.680429 containerd[1526]: time="2026-09-05T00:05:03.680178090Z" level=warning msg="cleaning up after shim disconnected" id=3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6 namespace=k8s.io Sep 5 00:05:03.680429 containerd[1526]: time="2026-09-05T00:05:03.680197950Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 5 00:05:04.307225 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3df983747e858bc22f2f47103e35d1c4afc2d063f3705fe6279546e609cd75f6-rootfs.mount: Deactivated successfully. Sep 5 00:05:04.495155 kubelet[1794]: E0905 00:05:04.495064 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:04.619318 containerd[1526]: time="2026-09-05T00:05:04.619262141Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 5 00:05:05.495939 kubelet[1794]: E0905 00:05:05.495889 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:06.497009 kubelet[1794]: E0905 00:05:06.496966 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:07.497291 kubelet[1794]: E0905 00:05:07.497251 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:07.502832 containerd[1526]: time="2026-09-05T00:05:07.502777942Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:07.503940 containerd[1526]: time="2026-09-05T00:05:07.503685893Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Sep 5 00:05:07.505478 containerd[1526]: time="2026-09-05T00:05:07.504805823Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:07.507441 containerd[1526]: time="2026-09-05T00:05:07.507407734Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:07.508441 containerd[1526]: time="2026-09-05T00:05:07.508359405Z" 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.889051684s" Sep 5 00:05:07.508441 containerd[1526]: time="2026-09-05T00:05:07.508384845Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Sep 5 00:05:07.511960 containerd[1526]: time="2026-09-05T00:05:07.511875006Z" level=info msg="CreateContainer within sandbox \"f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 5 00:05:07.528218 containerd[1526]: time="2026-09-05T00:05:07.528181213Z" level=info msg="CreateContainer within sandbox \"f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6\"" Sep 5 00:05:07.528711 containerd[1526]: time="2026-09-05T00:05:07.528688423Z" level=info msg="StartContainer for \"aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6\"" Sep 5 00:05:07.555587 systemd[1]: Started cri-containerd-aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6.scope - libcontainer container aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6. Sep 5 00:05:07.574909 systemd[1]: cri-containerd-aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6.scope: Deactivated successfully. Sep 5 00:05:07.579013 containerd[1526]: time="2026-09-05T00:05:07.578973194Z" level=info msg="StartContainer for \"aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6\" returns successfully" Sep 5 00:05:07.602315 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6-rootfs.mount: Deactivated successfully. Sep 5 00:05:07.604218 kubelet[1794]: I0905 00:05:07.604205 1794 kubelet_node_status.go:439] "Fast updating node status as it just became ready" Sep 5 00:05:07.662716 containerd[1526]: time="2026-09-05T00:05:07.662503219Z" level=info msg="shim disconnected" id=aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6 namespace=k8s.io Sep 5 00:05:07.662716 containerd[1526]: time="2026-09-05T00:05:07.662697899Z" level=warning msg="cleaning up after shim disconnected" id=aa0e2dd345f29c6937acc0b3dd4032fed088b01410fcae7406f1a2f6ea5454e6 namespace=k8s.io Sep 5 00:05:07.662716 containerd[1526]: time="2026-09-05T00:05:07.662708669Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 5 00:05:07.673218 containerd[1526]: time="2026-09-05T00:05:07.673145523Z" level=warning msg="cleanup warnings time=\"2026-09-05T00:05:07Z\" level=warning msg=\"failed to remove runc container\" error=\"runc did not terminate successfully: exit status 255: \" runtime=io.containerd.runc.v2\n" namespace=k8s.io Sep 5 00:05:08.498387 kubelet[1794]: E0905 00:05:08.498347 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:08.634506 containerd[1526]: time="2026-09-05T00:05:08.634416604Z" level=info msg="CreateContainer within sandbox \"f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 5 00:05:08.657053 containerd[1526]: time="2026-09-05T00:05:08.656928763Z" level=info msg="CreateContainer within sandbox \"f3121494fc4478f525eb7049cc405316c69793cc20242648ab62a33f394597e5\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"71d5130d24b8139e2bdaab6484306f6bb450954e2edb5bb5b592309108fdd910\"" Sep 5 00:05:08.657937 containerd[1526]: time="2026-09-05T00:05:08.657857403Z" level=info msg="StartContainer for \"71d5130d24b8139e2bdaab6484306f6bb450954e2edb5bb5b592309108fdd910\"" Sep 5 00:05:08.712645 systemd[1]: Started cri-containerd-71d5130d24b8139e2bdaab6484306f6bb450954e2edb5bb5b592309108fdd910.scope - libcontainer container 71d5130d24b8139e2bdaab6484306f6bb450954e2edb5bb5b592309108fdd910. Sep 5 00:05:08.750664 containerd[1526]: time="2026-09-05T00:05:08.750418322Z" level=info msg="StartContainer for \"71d5130d24b8139e2bdaab6484306f6bb450954e2edb5bb5b592309108fdd910\" returns successfully" Sep 5 00:05:09.498980 kubelet[1794]: E0905 00:05:09.498879 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:09.644748 kubelet[1794]: I0905 00:05:09.644670 1794 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-cjlkt" podStartSLOduration=4.589646566 podStartE2EDuration="11.644649804s" podCreationTimestamp="2026-09-05 00:04:58 +0000 UTC" firstStartedPulling="2026-09-05 00:05:00.454204047 +0000 UTC m=+3.367090014" lastFinishedPulling="2026-09-05 00:05:07.509207285 +0000 UTC m=+10.422093252" observedRunningTime="2026-09-05 00:05:09.644614504 +0000 UTC m=+12.557500511" watchObservedRunningTime="2026-09-05 00:05:09.644649804 +0000 UTC m=+12.557535781" Sep 5 00:05:09.687812 systemd[1]: Created slice kubepods-besteffort-podcca417ba_5a15_470e_8152_7113bef298f6.slice - libcontainer container kubepods-besteffort-podcca417ba_5a15_470e_8152_7113bef298f6.slice. Sep 5 00:05:09.791944 kubelet[1794]: I0905 00:05:09.791591 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-jp4vx\" (UniqueName: \"kubernetes.io/projected/cca417ba-5a15-470e-8152-7113bef298f6-kube-api-access-jp4vx\") pod \"nginx-deployment-bb8f74bfb-g4th8\" (UID: \"cca417ba-5a15-470e-8152-7113bef298f6\") " pod="default/nginx-deployment-bb8f74bfb-g4th8" Sep 5 00:05:09.810674 systemd-timesyncd[1464]: Network configuration changed, trying to establish connection. Sep 5 00:05:09.830059 systemd-networkd[1411]: flannel.1: Link UP Sep 5 00:05:09.830069 systemd-networkd[1411]: flannel.1: Gained carrier Sep 5 00:05:10.311889 systemd-resolved[1413]: Clock change detected. Flushing caches. Sep 5 00:05:10.312103 systemd-timesyncd[1464]: Contacted time server 185.233.107.180:123 (2.flatcar.pool.ntp.org). Sep 5 00:05:10.312161 systemd-timesyncd[1464]: Initial clock synchronization to Sat 2026-09-05 00:05:10.311811 UTC. Sep 5 00:05:10.458452 containerd[1526]: time="2026-09-05T00:05:10.458387847Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-g4th8,Uid:cca417ba-5a15-470e-8152-7113bef298f6,Namespace:default,Attempt:0,}" Sep 5 00:05:10.489641 systemd-networkd[1411]: cni0: Link UP Sep 5 00:05:10.489650 systemd-networkd[1411]: cni0: Gained carrier Sep 5 00:05:10.491236 systemd-networkd[1411]: cni0: Lost carrier Sep 5 00:05:10.500137 kernel: cni0: port 1(veth0c3e6ac6) entered blocking state Sep 5 00:05:10.500210 kernel: cni0: port 1(veth0c3e6ac6) entered disabled state Sep 5 00:05:10.498043 systemd-networkd[1411]: veth0c3e6ac6: Link UP Sep 5 00:05:10.503171 kernel: veth0c3e6ac6: entered allmulticast mode Sep 5 00:05:10.503215 kernel: veth0c3e6ac6: entered promiscuous mode Sep 5 00:05:10.505863 kernel: cni0: port 1(veth0c3e6ac6) entered blocking state Sep 5 00:05:10.505913 kernel: cni0: port 1(veth0c3e6ac6) entered forwarding state Sep 5 00:05:10.508508 kernel: cni0: port 1(veth0c3e6ac6) entered disabled state Sep 5 00:05:10.517963 kernel: cni0: port 1(veth0c3e6ac6) entered blocking state Sep 5 00:05:10.518078 kernel: cni0: port 1(veth0c3e6ac6) entered forwarding state Sep 5 00:05:10.517555 systemd-networkd[1411]: veth0c3e6ac6: Gained carrier Sep 5 00:05:10.519002 systemd-networkd[1411]: cni0: Gained carrier Sep 5 00:05:10.528056 containerd[1526]: 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"} Sep 5 00:05:10.528056 containerd[1526]: delegateAdd: netconf sent to delegate plugin: Sep 5 00:05:10.549460 containerd[1526]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2026-09-05T00:05:10.549288165Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 5 00:05:10.549460 containerd[1526]: time="2026-09-05T00:05:10.549327925Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 5 00:05:10.549460 containerd[1526]: time="2026-09-05T00:05:10.549335465Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:10.549460 containerd[1526]: time="2026-09-05T00:05:10.549424185Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:10.563035 systemd[1]: run-containerd-runc-k8s.io-e80bccad104cfbcb2563897b861703bed00940ae6ef9e0e8ec488d8991efe7cb-runc.Pbjwdp.mount: Deactivated successfully. Sep 5 00:05:10.571954 systemd[1]: Started cri-containerd-e80bccad104cfbcb2563897b861703bed00940ae6ef9e0e8ec488d8991efe7cb.scope - libcontainer container e80bccad104cfbcb2563897b861703bed00940ae6ef9e0e8ec488d8991efe7cb. Sep 5 00:05:10.600248 containerd[1526]: time="2026-09-05T00:05:10.600220896Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-g4th8,Uid:cca417ba-5a15-470e-8152-7113bef298f6,Namespace:default,Attempt:0,} returns sandbox id \"e80bccad104cfbcb2563897b861703bed00940ae6ef9e0e8ec488d8991efe7cb\"" Sep 5 00:05:10.601799 containerd[1526]: time="2026-09-05T00:05:10.601782446Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 5 00:05:10.962449 kubelet[1794]: E0905 00:05:10.962342 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:11.519290 systemd-networkd[1411]: flannel.1: Gained IPv6LL Sep 5 00:05:11.962794 kubelet[1794]: E0905 00:05:11.962707 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:12.031728 systemd-networkd[1411]: veth0c3e6ac6: Gained IPv6LL Sep 5 00:05:12.223139 systemd-networkd[1411]: cni0: Gained IPv6LL Sep 5 00:05:12.963739 kubelet[1794]: E0905 00:05:12.963665 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:13.965932 kubelet[1794]: E0905 00:05:13.965884 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:14.046510 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3364011413.mount: Deactivated successfully. Sep 5 00:05:14.659086 containerd[1526]: time="2026-09-05T00:05:14.659032656Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:14.660110 containerd[1526]: time="2026-09-05T00:05:14.660019287Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=64128731" Sep 5 00:05:14.660845 containerd[1526]: time="2026-09-05T00:05:14.660740547Z" level=info msg="ImageCreate event name:\"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:14.662900 containerd[1526]: time="2026-09-05T00:05:14.662669868Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:922a5603984560970f4e265613fdc25077f4826efbaf4a082670f2a86a3849f0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:14.663303 containerd[1526]: time="2026-09-05T00:05:14.663280178Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:922a5603984560970f4e265613fdc25077f4826efbaf4a082670f2a86a3849f0\", size \"64128609\" in 4.061475082s" Sep 5 00:05:14.663339 containerd[1526]: time="2026-09-05T00:05:14.663303318Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\"" Sep 5 00:05:14.666849 containerd[1526]: time="2026-09-05T00:05:14.666813969Z" level=info msg="CreateContainer within sandbox \"e80bccad104cfbcb2563897b861703bed00940ae6ef9e0e8ec488d8991efe7cb\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 5 00:05:14.682048 containerd[1526]: time="2026-09-05T00:05:14.682020376Z" level=info msg="CreateContainer within sandbox \"e80bccad104cfbcb2563897b861703bed00940ae6ef9e0e8ec488d8991efe7cb\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"9ca6f6325e4899a63d155542d9de36228636191cc3a1b4818aa8ec7a99db8f10\"" Sep 5 00:05:14.682469 containerd[1526]: time="2026-09-05T00:05:14.682447596Z" level=info msg="StartContainer for \"9ca6f6325e4899a63d155542d9de36228636191cc3a1b4818aa8ec7a99db8f10\"" Sep 5 00:05:14.707942 systemd[1]: Started cri-containerd-9ca6f6325e4899a63d155542d9de36228636191cc3a1b4818aa8ec7a99db8f10.scope - libcontainer container 9ca6f6325e4899a63d155542d9de36228636191cc3a1b4818aa8ec7a99db8f10. Sep 5 00:05:14.728846 containerd[1526]: time="2026-09-05T00:05:14.728799455Z" level=info msg="StartContainer for \"9ca6f6325e4899a63d155542d9de36228636191cc3a1b4818aa8ec7a99db8f10\" returns successfully" Sep 5 00:05:14.967268 kubelet[1794]: E0905 00:05:14.967047 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:15.967765 kubelet[1794]: E0905 00:05:15.967653 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:16.968462 kubelet[1794]: E0905 00:05:16.968386 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:17.827813 kubelet[1794]: I0905 00:05:17.827732 1794 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-bb8f74bfb-g4th8" podStartSLOduration=3.764411284 podStartE2EDuration="7.827711646s" podCreationTimestamp="2026-09-05 00:05:10 +0000 UTC" firstStartedPulling="2026-09-05 00:05:10.601077136 +0000 UTC m=+13.051589857" lastFinishedPulling="2026-09-05 00:05:14.664377508 +0000 UTC m=+17.114890219" observedRunningTime="2026-09-05 00:05:15.119247578 +0000 UTC m=+17.569760329" watchObservedRunningTime="2026-09-05 00:05:17.827711646 +0000 UTC m=+20.278224397" Sep 5 00:05:17.841555 systemd[1]: Created slice kubepods-besteffort-poda6fe5fe9_3e0d_45b1_9de8_7731d42a757c.slice - libcontainer container kubepods-besteffort-poda6fe5fe9_3e0d_45b1_9de8_7731d42a757c.slice. Sep 5 00:05:17.898928 kubelet[1794]: I0905 00:05:17.898868 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-896wk\" (UniqueName: \"kubernetes.io/projected/a6fe5fe9-3e0d-45b1-9de8-7731d42a757c-kube-api-access-896wk\") pod \"nfs-server-provisioner-0\" (UID: \"a6fe5fe9-3e0d-45b1-9de8-7731d42a757c\") " pod="default/nfs-server-provisioner-0" Sep 5 00:05:17.898928 kubelet[1794]: I0905 00:05:17.898916 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/a6fe5fe9-3e0d-45b1-9de8-7731d42a757c-data\") pod \"nfs-server-provisioner-0\" (UID: \"a6fe5fe9-3e0d-45b1-9de8-7731d42a757c\") " pod="default/nfs-server-provisioner-0" Sep 5 00:05:17.952045 kubelet[1794]: E0905 00:05:17.951937 1794 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:17.968676 kubelet[1794]: E0905 00:05:17.968627 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:18.149586 containerd[1526]: time="2026-09-05T00:05:18.149394550Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a6fe5fe9-3e0d-45b1-9de8-7731d42a757c,Namespace:default,Attempt:0,}" Sep 5 00:05:18.186263 kernel: cni0: port 2(vethf11e36eb) entered blocking state Sep 5 00:05:18.186361 kernel: cni0: port 2(vethf11e36eb) entered disabled state Sep 5 00:05:18.182371 systemd-networkd[1411]: vethf11e36eb: Link UP Sep 5 00:05:18.190667 kernel: vethf11e36eb: entered allmulticast mode Sep 5 00:05:18.190751 kernel: vethf11e36eb: entered promiscuous mode Sep 5 00:05:18.198244 kernel: cni0: port 2(vethf11e36eb) entered blocking state Sep 5 00:05:18.198312 kernel: cni0: port 2(vethf11e36eb) entered forwarding state Sep 5 00:05:18.198445 systemd-networkd[1411]: vethf11e36eb: Gained carrier Sep 5 00:05:18.201049 containerd[1526]: 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)(0xc000082950), "name":"cbr0", "type":"bridge"} Sep 5 00:05:18.201049 containerd[1526]: delegateAdd: netconf sent to delegate plugin: Sep 5 00:05:18.219586 containerd[1526]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2026-09-05T00:05:18.219510659Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 5 00:05:18.219586 containerd[1526]: time="2026-09-05T00:05:18.219553299Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 5 00:05:18.219586 containerd[1526]: time="2026-09-05T00:05:18.219563709Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:18.221275 containerd[1526]: time="2026-09-05T00:05:18.220160129Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:18.244961 systemd[1]: Started cri-containerd-122a36d6879bd55a3a3158bdad25483dc8eb1e5560f44c607ec3fe8bbfef974d.scope - libcontainer container 122a36d6879bd55a3a3158bdad25483dc8eb1e5560f44c607ec3fe8bbfef974d. Sep 5 00:05:18.275758 containerd[1526]: time="2026-09-05T00:05:18.275720753Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:a6fe5fe9-3e0d-45b1-9de8-7731d42a757c,Namespace:default,Attempt:0,} returns sandbox id \"122a36d6879bd55a3a3158bdad25483dc8eb1e5560f44c607ec3fe8bbfef974d\"" Sep 5 00:05:18.277385 containerd[1526]: time="2026-09-05T00:05:18.277354663Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 5 00:05:18.968928 kubelet[1794]: E0905 00:05:18.968753 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:19.455066 systemd-networkd[1411]: vethf11e36eb: Gained IPv6LL Sep 5 00:05:19.586927 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3107005766.mount: Deactivated successfully. Sep 5 00:05:19.969049 kubelet[1794]: E0905 00:05:19.968986 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:20.969844 kubelet[1794]: E0905 00:05:20.969773 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:20.988409 containerd[1526]: time="2026-09-05T00:05:20.988357382Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:20.989678 containerd[1526]: time="2026-09-05T00:05:20.989461433Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039380" Sep 5 00:05:20.990805 containerd[1526]: time="2026-09-05T00:05:20.990552873Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:20.993323 containerd[1526]: time="2026-09-05T00:05:20.993290694Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:20.994530 containerd[1526]: time="2026-09-05T00:05:20.994173365Z" 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.716782682s" Sep 5 00:05:20.994530 containerd[1526]: time="2026-09-05T00:05:20.994222635Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Sep 5 00:05:20.997564 containerd[1526]: time="2026-09-05T00:05:20.997531406Z" level=info msg="CreateContainer within sandbox \"122a36d6879bd55a3a3158bdad25483dc8eb1e5560f44c607ec3fe8bbfef974d\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 5 00:05:21.007786 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3947926073.mount: Deactivated successfully. Sep 5 00:05:21.014201 containerd[1526]: time="2026-09-05T00:05:21.014152733Z" level=info msg="CreateContainer within sandbox \"122a36d6879bd55a3a3158bdad25483dc8eb1e5560f44c607ec3fe8bbfef974d\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"9176de73a11e70ec05f325f0f2c78cb0670a1706277af46e705c3adb92719abc\"" Sep 5 00:05:21.014862 containerd[1526]: time="2026-09-05T00:05:21.014691873Z" level=info msg="StartContainer for \"9176de73a11e70ec05f325f0f2c78cb0670a1706277af46e705c3adb92719abc\"" Sep 5 00:05:21.039949 systemd[1]: Started cri-containerd-9176de73a11e70ec05f325f0f2c78cb0670a1706277af46e705c3adb92719abc.scope - libcontainer container 9176de73a11e70ec05f325f0f2c78cb0670a1706277af46e705c3adb92719abc. Sep 5 00:05:21.061592 containerd[1526]: time="2026-09-05T00:05:21.061524683Z" level=info msg="StartContainer for \"9176de73a11e70ec05f325f0f2c78cb0670a1706277af46e705c3adb92719abc\" returns successfully" Sep 5 00:05:21.135264 kubelet[1794]: I0905 00:05:21.135170 1794 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.417182541 podStartE2EDuration="4.135158143s" podCreationTimestamp="2026-09-05 00:05:17 +0000 UTC" firstStartedPulling="2026-09-05 00:05:18.276913993 +0000 UTC m=+20.727426714" lastFinishedPulling="2026-09-05 00:05:20.994889605 +0000 UTC m=+23.445402316" observedRunningTime="2026-09-05 00:05:21.135117623 +0000 UTC m=+23.585630334" watchObservedRunningTime="2026-09-05 00:05:21.135158143 +0000 UTC m=+23.585670854" Sep 5 00:05:21.971081 kubelet[1794]: E0905 00:05:21.970965 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:22.971750 kubelet[1794]: E0905 00:05:22.971650 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:23.972600 kubelet[1794]: E0905 00:05:23.972545 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:24.973350 kubelet[1794]: E0905 00:05:24.973288 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:25.973744 kubelet[1794]: E0905 00:05:25.973702 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:26.309059 systemd[1]: Created slice kubepods-besteffort-podeebbeba6_a908_4c13_b018_048a8005e7bb.slice - libcontainer container kubepods-besteffort-podeebbeba6_a908_4c13_b018_048a8005e7bb.slice. Sep 5 00:05:26.357482 kubelet[1794]: I0905 00:05:26.357351 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-6e5a2a4a-88af-4f9b-aa09-57a6d0f4c816\" (UniqueName: \"kubernetes.io/nfs/eebbeba6-a908-4c13-b018-048a8005e7bb-pvc-6e5a2a4a-88af-4f9b-aa09-57a6d0f4c816\") pod \"test-pod-1\" (UID: \"eebbeba6-a908-4c13-b018-048a8005e7bb\") " pod="default/test-pod-1" Sep 5 00:05:26.357482 kubelet[1794]: I0905 00:05:26.357413 1794 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hgs4v\" (UniqueName: \"kubernetes.io/projected/eebbeba6-a908-4c13-b018-048a8005e7bb-kube-api-access-hgs4v\") pod \"test-pod-1\" (UID: \"eebbeba6-a908-4c13-b018-048a8005e7bb\") " pod="default/test-pod-1" Sep 5 00:05:26.513158 kernel: FS-Cache: Loaded Sep 5 00:05:26.589405 kernel: RPC: Registered named UNIX socket transport module. Sep 5 00:05:26.589586 kernel: RPC: Registered udp transport module. Sep 5 00:05:26.589628 kernel: RPC: Registered tcp transport module. Sep 5 00:05:26.590727 kernel: RPC: Registered tcp-with-tls transport module. Sep 5 00:05:26.593635 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 5 00:05:26.809957 kernel: NFS: Registering the id_resolver key type Sep 5 00:05:26.812665 kernel: Key type id_resolver registered Sep 5 00:05:26.812795 kernel: Key type id_legacy registered Sep 5 00:05:26.857725 nfsidmap[2775]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 5 00:05:26.861126 nfsidmap[2776]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 5 00:05:26.918096 containerd[1526]: time="2026-09-05T00:05:26.918031732Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:eebbeba6-a908-4c13-b018-048a8005e7bb,Namespace:default,Attempt:0,}" Sep 5 00:05:26.953192 systemd-networkd[1411]: veth96af948d: Link UP Sep 5 00:05:26.960493 kernel: cni0: port 3(veth96af948d) entered blocking state Sep 5 00:05:26.960561 kernel: cni0: port 3(veth96af948d) entered disabled state Sep 5 00:05:26.960589 kernel: veth96af948d: entered allmulticast mode Sep 5 00:05:26.965175 kernel: veth96af948d: entered promiscuous mode Sep 5 00:05:26.975806 kernel: cni0: port 3(veth96af948d) entered blocking state Sep 5 00:05:26.976583 kernel: cni0: port 3(veth96af948d) entered forwarding state Sep 5 00:05:26.975986 systemd-networkd[1411]: veth96af948d: Gained carrier Sep 5 00:05:26.976805 kubelet[1794]: E0905 00:05:26.975917 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:26.978960 containerd[1526]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Sep 5 00:05:26.978960 containerd[1526]: delegateAdd: netconf sent to delegate plugin: Sep 5 00:05:27.003343 containerd[1526]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2026-09-05T00:05:27.003180647Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 5 00:05:27.003343 containerd[1526]: time="2026-09-05T00:05:27.003228137Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 5 00:05:27.003343 containerd[1526]: time="2026-09-05T00:05:27.003243057Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:27.003641 containerd[1526]: time="2026-09-05T00:05:27.003483668Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 5 00:05:27.024970 systemd[1]: Started cri-containerd-839a2e41efe4a29cbdc259d01d171eec6a7dddd4f397bf38f7eaf9b30a9e1119.scope - libcontainer container 839a2e41efe4a29cbdc259d01d171eec6a7dddd4f397bf38f7eaf9b30a9e1119. Sep 5 00:05:27.058973 containerd[1526]: time="2026-09-05T00:05:27.058934121Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:eebbeba6-a908-4c13-b018-048a8005e7bb,Namespace:default,Attempt:0,} returns sandbox id \"839a2e41efe4a29cbdc259d01d171eec6a7dddd4f397bf38f7eaf9b30a9e1119\"" Sep 5 00:05:27.060874 containerd[1526]: time="2026-09-05T00:05:27.060381701Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 5 00:05:27.545729 containerd[1526]: time="2026-09-05T00:05:27.545652613Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 5 00:05:27.547158 containerd[1526]: time="2026-09-05T00:05:27.547077204Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 5 00:05:27.551641 containerd[1526]: time="2026-09-05T00:05:27.551588366Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:922a5603984560970f4e265613fdc25077f4826efbaf4a082670f2a86a3849f0\", size \"64128609\" in 491.181235ms" Sep 5 00:05:27.551641 containerd[1526]: time="2026-09-05T00:05:27.551637676Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:ba5b7c1c189a2f9add5350a57010cc38faaeeb3bb3aed5c2bcfb96a37bd5f773\"" Sep 5 00:05:27.559127 containerd[1526]: time="2026-09-05T00:05:27.559067459Z" level=info msg="CreateContainer within sandbox \"839a2e41efe4a29cbdc259d01d171eec6a7dddd4f397bf38f7eaf9b30a9e1119\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 5 00:05:27.581038 containerd[1526]: time="2026-09-05T00:05:27.580955868Z" level=info msg="CreateContainer within sandbox \"839a2e41efe4a29cbdc259d01d171eec6a7dddd4f397bf38f7eaf9b30a9e1119\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"2e4c3203df08749696a587aacaa4a5efa1a225e2822605ef0956013970043ab9\"" Sep 5 00:05:27.583372 containerd[1526]: time="2026-09-05T00:05:27.581979328Z" level=info msg="StartContainer for \"2e4c3203df08749696a587aacaa4a5efa1a225e2822605ef0956013970043ab9\"" Sep 5 00:05:27.634100 systemd[1]: Started cri-containerd-2e4c3203df08749696a587aacaa4a5efa1a225e2822605ef0956013970043ab9.scope - libcontainer container 2e4c3203df08749696a587aacaa4a5efa1a225e2822605ef0956013970043ab9. Sep 5 00:05:27.667867 containerd[1526]: time="2026-09-05T00:05:27.667782634Z" level=info msg="StartContainer for \"2e4c3203df08749696a587aacaa4a5efa1a225e2822605ef0956013970043ab9\" returns successfully" Sep 5 00:05:27.977002 kubelet[1794]: E0905 00:05:27.976901 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:28.095170 systemd-networkd[1411]: veth96af948d: Gained IPv6LL Sep 5 00:05:28.151023 kubelet[1794]: I0905 00:05:28.150927 1794 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=9.65826452 podStartE2EDuration="10.150900435s" podCreationTimestamp="2026-09-05 00:05:18 +0000 UTC" firstStartedPulling="2026-09-05 00:05:27.059864111 +0000 UTC m=+29.510376822" lastFinishedPulling="2026-09-05 00:05:27.552499976 +0000 UTC m=+30.003012737" observedRunningTime="2026-09-05 00:05:28.150654825 +0000 UTC m=+30.601167576" watchObservedRunningTime="2026-09-05 00:05:28.150900435 +0000 UTC m=+30.601413186" Sep 5 00:05:28.978047 kubelet[1794]: E0905 00:05:28.977972 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:29.978601 kubelet[1794]: E0905 00:05:29.978525 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:30.979049 kubelet[1794]: E0905 00:05:30.978956 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:31.979200 kubelet[1794]: E0905 00:05:31.979103 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:32.980050 kubelet[1794]: E0905 00:05:32.979961 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:33.623780 update_engine[1504]: I20260905 00:05:33.622597 1504 update_attempter.cc:509] Updating boot flags... Sep 5 00:05:33.693322 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 31 scanned by (udev-worker) (2940) Sep 5 00:05:33.758995 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 31 scanned by (udev-worker) (2944) Sep 5 00:05:33.820039 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 31 scanned by (udev-worker) (2944) Sep 5 00:05:33.981168 kubelet[1794]: E0905 00:05:33.980958 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 5 00:05:34.981981 kubelet[1794]: E0905 00:05:34.981934 1794 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"