Apr 30 12:52:40.045911 kernel: Linux version 6.6.88-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Tue Apr 29 22:26:36 -00 2025 Apr 30 12:52:40.045942 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=digitalocean verity.usrhash=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:52:40.045956 kernel: BIOS-provided physical RAM map: Apr 30 12:52:40.045963 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Apr 30 12:52:40.045970 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Apr 30 12:52:40.045977 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Apr 30 12:52:40.045986 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdafff] usable Apr 30 12:52:40.045993 kernel: BIOS-e820: [mem 0x000000007ffdb000-0x000000007fffffff] reserved Apr 30 12:52:40.046000 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Apr 30 12:52:40.046007 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Apr 30 12:52:40.046018 kernel: NX (Execute Disable) protection: active Apr 30 12:52:40.046025 kernel: APIC: Static calls initialized Apr 30 12:52:40.046036 kernel: SMBIOS 2.8 present. Apr 30 12:52:40.046044 kernel: DMI: DigitalOcean Droplet/Droplet, BIOS 20171212 12/12/2017 Apr 30 12:52:40.047822 kernel: Hypervisor detected: KVM Apr 30 12:52:40.047863 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Apr 30 12:52:40.047898 kernel: kvm-clock: using sched offset of 3976378008 cycles Apr 30 12:52:40.047921 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Apr 30 12:52:40.047934 kernel: tsc: Detected 2294.608 MHz processor Apr 30 12:52:40.047948 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Apr 30 12:52:40.047971 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Apr 30 12:52:40.047995 kernel: last_pfn = 0x7ffdb max_arch_pfn = 0x400000000 Apr 30 12:52:40.048016 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Apr 30 12:52:40.048034 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Apr 30 12:52:40.048079 kernel: ACPI: Early table checksum verification disabled Apr 30 12:52:40.048098 kernel: ACPI: RSDP 0x00000000000F5950 000014 (v00 BOCHS ) Apr 30 12:52:40.048116 kernel: ACPI: RSDT 0x000000007FFE1986 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 12:52:40.048134 kernel: ACPI: FACP 0x000000007FFE176A 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 12:52:40.048153 kernel: ACPI: DSDT 0x000000007FFE0040 00172A (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 12:52:40.048170 kernel: ACPI: FACS 0x000000007FFE0000 000040 Apr 30 12:52:40.048183 kernel: ACPI: APIC 0x000000007FFE17DE 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 12:52:40.048201 kernel: ACPI: HPET 0x000000007FFE185E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 12:52:40.048224 kernel: ACPI: SRAT 0x000000007FFE1896 0000C8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 12:52:40.048248 kernel: ACPI: WAET 0x000000007FFE195E 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 30 12:52:40.048266 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe176a-0x7ffe17dd] Apr 30 12:52:40.048283 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe1769] Apr 30 12:52:40.048301 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Apr 30 12:52:40.048319 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17de-0x7ffe185d] Apr 30 12:52:40.048337 kernel: ACPI: Reserving HPET table memory at [mem 0x7ffe185e-0x7ffe1895] Apr 30 12:52:40.048355 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe1896-0x7ffe195d] Apr 30 12:52:40.048380 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe195e-0x7ffe1985] Apr 30 12:52:40.048402 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Apr 30 12:52:40.048421 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Apr 30 12:52:40.048441 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Apr 30 12:52:40.048460 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Apr 30 12:52:40.048484 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffdafff] -> [mem 0x00000000-0x7ffdafff] Apr 30 12:52:40.048504 kernel: NODE_DATA(0) allocated [mem 0x7ffd5000-0x7ffdafff] Apr 30 12:52:40.048527 kernel: Zone ranges: Apr 30 12:52:40.048547 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Apr 30 12:52:40.048583 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdafff] Apr 30 12:52:40.048605 kernel: Normal empty Apr 30 12:52:40.048624 kernel: Movable zone start for each node Apr 30 12:52:40.048643 kernel: Early memory node ranges Apr 30 12:52:40.048662 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Apr 30 12:52:40.048681 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdafff] Apr 30 12:52:40.048700 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdafff] Apr 30 12:52:40.048724 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Apr 30 12:52:40.048743 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Apr 30 12:52:40.048765 kernel: On node 0, zone DMA32: 37 pages in unavailable ranges Apr 30 12:52:40.048785 kernel: ACPI: PM-Timer IO Port: 0x608 Apr 30 12:52:40.048804 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Apr 30 12:52:40.048823 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Apr 30 12:52:40.048842 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Apr 30 12:52:40.048861 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Apr 30 12:52:40.048880 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Apr 30 12:52:40.048899 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Apr 30 12:52:40.048922 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Apr 30 12:52:40.048941 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Apr 30 12:52:40.048960 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Apr 30 12:52:40.048979 kernel: TSC deadline timer available Apr 30 12:52:40.048998 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Apr 30 12:52:40.049018 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Apr 30 12:52:40.049037 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Apr 30 12:52:40.049080 kernel: Booting paravirtualized kernel on KVM Apr 30 12:52:40.049100 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Apr 30 12:52:40.049123 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Apr 30 12:52:40.049142 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u1048576 Apr 30 12:52:40.049161 kernel: pcpu-alloc: s197096 r8192 d32280 u1048576 alloc=1*2097152 Apr 30 12:52:40.049180 kernel: pcpu-alloc: [0] 0 1 Apr 30 12:52:40.049199 kernel: kvm-guest: PV spinlocks disabled, no host support Apr 30 12:52:40.049220 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=digitalocean verity.usrhash=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:52:40.049240 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 30 12:52:40.049256 kernel: random: crng init done Apr 30 12:52:40.049274 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 30 12:52:40.049286 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Apr 30 12:52:40.049303 kernel: Fallback order for Node 0: 0 Apr 30 12:52:40.049316 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515803 Apr 30 12:52:40.049328 kernel: Policy zone: DMA32 Apr 30 12:52:40.049340 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 30 12:52:40.049353 kernel: Memory: 1969156K/2096612K available (14336K kernel code, 2295K rwdata, 22864K rodata, 43484K init, 1592K bss, 127196K reserved, 0K cma-reserved) Apr 30 12:52:40.049374 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Apr 30 12:52:40.049398 kernel: Kernel/User page tables isolation: enabled Apr 30 12:52:40.049426 kernel: ftrace: allocating 37918 entries in 149 pages Apr 30 12:52:40.049450 kernel: ftrace: allocated 149 pages with 4 groups Apr 30 12:52:40.049473 kernel: Dynamic Preempt: voluntary Apr 30 12:52:40.049497 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 30 12:52:40.049521 kernel: rcu: RCU event tracing is enabled. Apr 30 12:52:40.049545 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Apr 30 12:52:40.049569 kernel: Trampoline variant of Tasks RCU enabled. Apr 30 12:52:40.049593 kernel: Rude variant of Tasks RCU enabled. Apr 30 12:52:40.049608 kernel: Tracing variant of Tasks RCU enabled. Apr 30 12:52:40.049627 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 30 12:52:40.049645 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Apr 30 12:52:40.049664 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Apr 30 12:52:40.049683 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 30 12:52:40.049708 kernel: Console: colour VGA+ 80x25 Apr 30 12:52:40.049728 kernel: printk: console [tty0] enabled Apr 30 12:52:40.049748 kernel: printk: console [ttyS0] enabled Apr 30 12:52:40.049768 kernel: ACPI: Core revision 20230628 Apr 30 12:52:40.049795 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Apr 30 12:52:40.049817 kernel: APIC: Switch to symmetric I/O mode setup Apr 30 12:52:40.049831 kernel: x2apic enabled Apr 30 12:52:40.049845 kernel: APIC: Switched APIC routing to: physical x2apic Apr 30 12:52:40.049857 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Apr 30 12:52:40.049870 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns Apr 30 12:52:40.049884 kernel: Calibrating delay loop (skipped) preset value.. 4589.21 BogoMIPS (lpj=2294608) Apr 30 12:52:40.049899 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Apr 30 12:52:40.049925 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Apr 30 12:52:40.049974 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Apr 30 12:52:40.049996 kernel: Spectre V2 : Mitigation: Retpolines Apr 30 12:52:40.050017 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Apr 30 12:52:40.050041 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Apr 30 12:52:40.052361 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Apr 30 12:52:40.052390 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Apr 30 12:52:40.052411 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Apr 30 12:52:40.052432 kernel: MDS: Mitigation: Clear CPU buffers Apr 30 12:52:40.052453 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Apr 30 12:52:40.052492 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Apr 30 12:52:40.052513 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Apr 30 12:52:40.052533 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Apr 30 12:52:40.052554 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Apr 30 12:52:40.052661 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Apr 30 12:52:40.052681 kernel: Freeing SMP alternatives memory: 32K Apr 30 12:52:40.052704 kernel: pid_max: default: 32768 minimum: 301 Apr 30 12:52:40.052728 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 30 12:52:40.052749 kernel: landlock: Up and running. Apr 30 12:52:40.052765 kernel: SELinux: Initializing. Apr 30 12:52:40.052779 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Apr 30 12:52:40.052793 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Apr 30 12:52:40.052806 kernel: smpboot: CPU0: Intel DO-Regular (family: 0x6, model: 0x4f, stepping: 0x1) Apr 30 12:52:40.052819 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 12:52:40.052832 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 12:52:40.052855 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Apr 30 12:52:40.052882 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Apr 30 12:52:40.052914 kernel: signal: max sigframe size: 1776 Apr 30 12:52:40.052935 kernel: rcu: Hierarchical SRCU implementation. Apr 30 12:52:40.052957 kernel: rcu: Max phase no-delay instances is 400. Apr 30 12:52:40.052978 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Apr 30 12:52:40.052998 kernel: smp: Bringing up secondary CPUs ... Apr 30 12:52:40.053019 kernel: smpboot: x86: Booting SMP configuration: Apr 30 12:52:40.053040 kernel: .... node #0, CPUs: #1 Apr 30 12:52:40.053087 kernel: smp: Brought up 1 node, 2 CPUs Apr 30 12:52:40.054499 kernel: smpboot: Max logical packages: 1 Apr 30 12:52:40.054533 kernel: smpboot: Total of 2 processors activated (9178.43 BogoMIPS) Apr 30 12:52:40.054554 kernel: devtmpfs: initialized Apr 30 12:52:40.054575 kernel: x86/mm: Memory block size: 128MB Apr 30 12:52:40.054599 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 30 12:52:40.054619 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Apr 30 12:52:40.054633 kernel: pinctrl core: initialized pinctrl subsystem Apr 30 12:52:40.054648 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 30 12:52:40.054668 kernel: audit: initializing netlink subsys (disabled) Apr 30 12:52:40.054693 kernel: audit: type=2000 audit(1746017558.218:1): state=initialized audit_enabled=0 res=1 Apr 30 12:52:40.054724 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 30 12:52:40.054745 kernel: thermal_sys: Registered thermal governor 'user_space' Apr 30 12:52:40.054765 kernel: cpuidle: using governor menu Apr 30 12:52:40.054786 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 30 12:52:40.054806 kernel: dca service started, version 1.12.1 Apr 30 12:52:40.054827 kernel: PCI: Using configuration type 1 for base access Apr 30 12:52:40.054848 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Apr 30 12:52:40.054868 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 30 12:52:40.054888 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Apr 30 12:52:40.054912 kernel: ACPI: Added _OSI(Module Device) Apr 30 12:52:40.054933 kernel: ACPI: Added _OSI(Processor Device) Apr 30 12:52:40.055106 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 30 12:52:40.055130 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 30 12:52:40.055150 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 30 12:52:40.055171 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Apr 30 12:52:40.055191 kernel: ACPI: Interpreter enabled Apr 30 12:52:40.055212 kernel: ACPI: PM: (supports S0 S5) Apr 30 12:52:40.055233 kernel: ACPI: Using IOAPIC for interrupt routing Apr 30 12:52:40.055259 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Apr 30 12:52:40.055280 kernel: PCI: Using E820 reservations for host bridge windows Apr 30 12:52:40.055301 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Apr 30 12:52:40.055326 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Apr 30 12:52:40.055649 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Apr 30 12:52:40.055822 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Apr 30 12:52:40.055992 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Apr 30 12:52:40.056026 kernel: acpiphp: Slot [3] registered Apr 30 12:52:40.056047 kernel: acpiphp: Slot [4] registered Apr 30 12:52:40.058194 kernel: acpiphp: Slot [5] registered Apr 30 12:52:40.058208 kernel: acpiphp: Slot [6] registered Apr 30 12:52:40.058218 kernel: acpiphp: Slot [7] registered Apr 30 12:52:40.058227 kernel: acpiphp: Slot [8] registered Apr 30 12:52:40.058237 kernel: acpiphp: Slot [9] registered Apr 30 12:52:40.058247 kernel: acpiphp: Slot [10] registered Apr 30 12:52:40.058256 kernel: acpiphp: Slot [11] registered Apr 30 12:52:40.058265 kernel: acpiphp: Slot [12] registered Apr 30 12:52:40.058282 kernel: acpiphp: Slot [13] registered Apr 30 12:52:40.058292 kernel: acpiphp: Slot [14] registered Apr 30 12:52:40.058301 kernel: acpiphp: Slot [15] registered Apr 30 12:52:40.058316 kernel: acpiphp: Slot [16] registered Apr 30 12:52:40.058341 kernel: acpiphp: Slot [17] registered Apr 30 12:52:40.058356 kernel: acpiphp: Slot [18] registered Apr 30 12:52:40.058370 kernel: acpiphp: Slot [19] registered Apr 30 12:52:40.058384 kernel: acpiphp: Slot [20] registered Apr 30 12:52:40.058398 kernel: acpiphp: Slot [21] registered Apr 30 12:52:40.058426 kernel: acpiphp: Slot [22] registered Apr 30 12:52:40.058447 kernel: acpiphp: Slot [23] registered Apr 30 12:52:40.058467 kernel: acpiphp: Slot [24] registered Apr 30 12:52:40.058488 kernel: acpiphp: Slot [25] registered Apr 30 12:52:40.058508 kernel: acpiphp: Slot [26] registered Apr 30 12:52:40.058529 kernel: acpiphp: Slot [27] registered Apr 30 12:52:40.058549 kernel: acpiphp: Slot [28] registered Apr 30 12:52:40.058579 kernel: acpiphp: Slot [29] registered Apr 30 12:52:40.058596 kernel: acpiphp: Slot [30] registered Apr 30 12:52:40.058614 kernel: acpiphp: Slot [31] registered Apr 30 12:52:40.058628 kernel: PCI host bridge to bus 0000:00 Apr 30 12:52:40.058877 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Apr 30 12:52:40.059079 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Apr 30 12:52:40.059233 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Apr 30 12:52:40.059365 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Apr 30 12:52:40.059495 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Apr 30 12:52:40.059627 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 30 12:52:40.059815 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Apr 30 12:52:40.060020 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Apr 30 12:52:40.063338 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Apr 30 12:52:40.063514 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc1e0-0xc1ef] Apr 30 12:52:40.063667 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Apr 30 12:52:40.063833 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Apr 30 12:52:40.064025 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Apr 30 12:52:40.064372 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Apr 30 12:52:40.064593 kernel: pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 Apr 30 12:52:40.064768 kernel: pci 0000:00:01.2: reg 0x20: [io 0xc180-0xc19f] Apr 30 12:52:40.064963 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Apr 30 12:52:40.065761 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Apr 30 12:52:40.065960 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Apr 30 12:52:40.066168 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Apr 30 12:52:40.066357 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Apr 30 12:52:40.066523 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Apr 30 12:52:40.066719 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf0fff] Apr 30 12:52:40.066844 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] Apr 30 12:52:40.066949 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Apr 30 12:52:40.067091 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Apr 30 12:52:40.067207 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc1a0-0xc1bf] Apr 30 12:52:40.067310 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] Apr 30 12:52:40.067415 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Apr 30 12:52:40.067533 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Apr 30 12:52:40.067637 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc1c0-0xc1df] Apr 30 12:52:40.067743 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] Apr 30 12:52:40.067852 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Apr 30 12:52:40.068002 kernel: pci 0000:00:05.0: [1af4:1004] type 00 class 0x010000 Apr 30 12:52:40.074296 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc100-0xc13f] Apr 30 12:52:40.074477 kernel: pci 0000:00:05.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] Apr 30 12:52:40.074658 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Apr 30 12:52:40.074839 kernel: pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 Apr 30 12:52:40.074992 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc07f] Apr 30 12:52:40.075226 kernel: pci 0000:00:06.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] Apr 30 12:52:40.075378 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Apr 30 12:52:40.075534 kernel: pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 Apr 30 12:52:40.075702 kernel: pci 0000:00:07.0: reg 0x10: [io 0xc080-0xc0ff] Apr 30 12:52:40.075863 kernel: pci 0000:00:07.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] Apr 30 12:52:40.078121 kernel: pci 0000:00:07.0: reg 0x20: [mem 0xfe814000-0xfe817fff 64bit pref] Apr 30 12:52:40.078364 kernel: pci 0000:00:08.0: [1af4:1002] type 00 class 0x00ff00 Apr 30 12:52:40.078484 kernel: pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] Apr 30 12:52:40.078587 kernel: pci 0000:00:08.0: reg 0x20: [mem 0xfe818000-0xfe81bfff 64bit pref] Apr 30 12:52:40.078600 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Apr 30 12:52:40.078610 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Apr 30 12:52:40.078620 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Apr 30 12:52:40.078629 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Apr 30 12:52:40.078640 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Apr 30 12:52:40.078653 kernel: iommu: Default domain type: Translated Apr 30 12:52:40.078663 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Apr 30 12:52:40.078672 kernel: PCI: Using ACPI for IRQ routing Apr 30 12:52:40.078682 kernel: PCI: pci_cache_line_size set to 64 bytes Apr 30 12:52:40.078691 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Apr 30 12:52:40.078701 kernel: e820: reserve RAM buffer [mem 0x7ffdb000-0x7fffffff] Apr 30 12:52:40.078803 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Apr 30 12:52:40.078904 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Apr 30 12:52:40.079008 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Apr 30 12:52:40.079021 kernel: vgaarb: loaded Apr 30 12:52:40.079030 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Apr 30 12:52:40.079040 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Apr 30 12:52:40.079052 kernel: clocksource: Switched to clocksource kvm-clock Apr 30 12:52:40.079130 kernel: VFS: Disk quotas dquot_6.6.0 Apr 30 12:52:40.079144 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 30 12:52:40.079158 kernel: pnp: PnP ACPI init Apr 30 12:52:40.079181 kernel: pnp: PnP ACPI: found 4 devices Apr 30 12:52:40.079208 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Apr 30 12:52:40.079229 kernel: NET: Registered PF_INET protocol family Apr 30 12:52:40.079250 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 30 12:52:40.079271 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Apr 30 12:52:40.079291 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 30 12:52:40.079312 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Apr 30 12:52:40.079333 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Apr 30 12:52:40.079353 kernel: TCP: Hash tables configured (established 16384 bind 16384) Apr 30 12:52:40.079374 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Apr 30 12:52:40.079398 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Apr 30 12:52:40.079418 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 30 12:52:40.079439 kernel: NET: Registered PF_XDP protocol family Apr 30 12:52:40.079592 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Apr 30 12:52:40.079722 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Apr 30 12:52:40.079855 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Apr 30 12:52:40.079995 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Apr 30 12:52:40.082279 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Apr 30 12:52:40.082440 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Apr 30 12:52:40.082550 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Apr 30 12:52:40.082566 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Apr 30 12:52:40.082668 kernel: pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x7a0 took 44554 usecs Apr 30 12:52:40.082681 kernel: PCI: CLS 0 bytes, default 64 Apr 30 12:52:40.082691 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Apr 30 12:52:40.082701 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns Apr 30 12:52:40.082711 kernel: Initialise system trusted keyrings Apr 30 12:52:40.082721 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Apr 30 12:52:40.082735 kernel: Key type asymmetric registered Apr 30 12:52:40.082744 kernel: Asymmetric key parser 'x509' registered Apr 30 12:52:40.082754 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Apr 30 12:52:40.082763 kernel: io scheduler mq-deadline registered Apr 30 12:52:40.082773 kernel: io scheduler kyber registered Apr 30 12:52:40.082782 kernel: io scheduler bfq registered Apr 30 12:52:40.082791 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Apr 30 12:52:40.082801 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Apr 30 12:52:40.082811 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Apr 30 12:52:40.082823 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Apr 30 12:52:40.082832 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 30 12:52:40.082842 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Apr 30 12:52:40.082852 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Apr 30 12:52:40.082861 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Apr 30 12:52:40.082871 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Apr 30 12:52:40.082881 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Apr 30 12:52:40.083029 kernel: rtc_cmos 00:03: RTC can wake from S4 Apr 30 12:52:40.083183 kernel: rtc_cmos 00:03: registered as rtc0 Apr 30 12:52:40.083281 kernel: rtc_cmos 00:03: setting system clock to 2025-04-30T12:52:39 UTC (1746017559) Apr 30 12:52:40.083388 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Apr 30 12:52:40.083408 kernel: intel_pstate: CPU model not supported Apr 30 12:52:40.083423 kernel: NET: Registered PF_INET6 protocol family Apr 30 12:52:40.083438 kernel: Segment Routing with IPv6 Apr 30 12:52:40.083461 kernel: In-situ OAM (IOAM) with IPv6 Apr 30 12:52:40.083481 kernel: NET: Registered PF_PACKET protocol family Apr 30 12:52:40.083509 kernel: Key type dns_resolver registered Apr 30 12:52:40.083529 kernel: IPI shorthand broadcast: enabled Apr 30 12:52:40.083550 kernel: sched_clock: Marking stable (1245008399, 178456414)->(1620917785, -197452972) Apr 30 12:52:40.083571 kernel: registered taskstats version 1 Apr 30 12:52:40.083591 kernel: Loading compiled-in X.509 certificates Apr 30 12:52:40.083611 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: 10d2d341d26c1df942e743344427c053ef3a2a5f' Apr 30 12:52:40.083633 kernel: Key type .fscrypt registered Apr 30 12:52:40.083647 kernel: Key type fscrypt-provisioning registered Apr 30 12:52:40.083665 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 30 12:52:40.083686 kernel: ima: Allocated hash algorithm: sha1 Apr 30 12:52:40.083705 kernel: ima: No architecture policies found Apr 30 12:52:40.083722 kernel: clk: Disabling unused clocks Apr 30 12:52:40.083747 kernel: Freeing unused kernel image (initmem) memory: 43484K Apr 30 12:52:40.083770 kernel: Write protecting the kernel read-only data: 38912k Apr 30 12:52:40.083814 kernel: Freeing unused kernel image (rodata/data gap) memory: 1712K Apr 30 12:52:40.083839 kernel: Run /init as init process Apr 30 12:52:40.083861 kernel: with arguments: Apr 30 12:52:40.083882 kernel: /init Apr 30 12:52:40.083909 kernel: with environment: Apr 30 12:52:40.083924 kernel: HOME=/ Apr 30 12:52:40.083939 kernel: TERM=linux Apr 30 12:52:40.083956 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 30 12:52:40.083976 systemd[1]: Successfully made /usr/ read-only. Apr 30 12:52:40.084002 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Apr 30 12:52:40.084032 systemd[1]: Detected virtualization kvm. Apr 30 12:52:40.086175 systemd[1]: Detected architecture x86-64. Apr 30 12:52:40.086220 systemd[1]: Running in initrd. Apr 30 12:52:40.086245 systemd[1]: No hostname configured, using default hostname. Apr 30 12:52:40.086260 systemd[1]: Hostname set to . Apr 30 12:52:40.086275 systemd[1]: Initializing machine ID from VM UUID. Apr 30 12:52:40.086293 systemd[1]: Queued start job for default target initrd.target. Apr 30 12:52:40.086311 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:52:40.086322 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:52:40.086334 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 30 12:52:40.086349 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 12:52:40.086360 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 30 12:52:40.086372 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 30 12:52:40.086384 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 30 12:52:40.086394 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 30 12:52:40.086405 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:52:40.086415 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:52:40.086428 systemd[1]: Reached target paths.target - Path Units. Apr 30 12:52:40.086439 systemd[1]: Reached target slices.target - Slice Units. Apr 30 12:52:40.086453 systemd[1]: Reached target swap.target - Swaps. Apr 30 12:52:40.086463 systemd[1]: Reached target timers.target - Timer Units. Apr 30 12:52:40.086474 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 12:52:40.086487 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 12:52:40.086497 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 30 12:52:40.086508 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Apr 30 12:52:40.086519 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:52:40.086530 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 12:52:40.086540 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:52:40.086550 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 12:52:40.086561 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 30 12:52:40.086571 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 12:52:40.086585 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 30 12:52:40.086595 systemd[1]: Starting systemd-fsck-usr.service... Apr 30 12:52:40.086606 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 12:52:40.086616 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 12:52:40.086626 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:52:40.086702 systemd-journald[183]: Collecting audit messages is disabled. Apr 30 12:52:40.086732 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 30 12:52:40.086742 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:52:40.086754 systemd[1]: Finished systemd-fsck-usr.service. Apr 30 12:52:40.086768 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 30 12:52:40.086781 systemd-journald[183]: Journal started Apr 30 12:52:40.086805 systemd-journald[183]: Runtime Journal (/run/log/journal/bb19098ad7284cea9403407dbb8ecdb2) is 4.9M, max 39.3M, 34.4M free. Apr 30 12:52:40.056476 systemd-modules-load[184]: Inserted module 'overlay' Apr 30 12:52:40.122483 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 30 12:52:40.122532 kernel: Bridge firewalling registered Apr 30 12:52:40.106634 systemd-modules-load[184]: Inserted module 'br_netfilter' Apr 30 12:52:40.132095 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 12:52:40.131492 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 12:52:40.135440 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:52:40.138929 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 30 12:52:40.147333 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:52:40.149303 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 12:52:40.153341 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 12:52:40.159327 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 12:52:40.181820 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:52:40.184366 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:52:40.187445 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:52:40.194275 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 12:52:40.195246 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:52:40.204528 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 30 12:52:40.222156 dracut-cmdline[220]: dracut-dracut-053 Apr 30 12:52:40.226988 dracut-cmdline[220]: 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=digitalocean verity.usrhash=95dd3de5eb34971546a976dc51c66bc73cf59b888896e27767c0cbf245cb98fe Apr 30 12:52:40.247143 systemd-resolved[217]: Positive Trust Anchors: Apr 30 12:52:40.247157 systemd-resolved[217]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 12:52:40.247196 systemd-resolved[217]: 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 Apr 30 12:52:40.251078 systemd-resolved[217]: Defaulting to hostname 'linux'. Apr 30 12:52:40.253474 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 12:52:40.254173 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:52:40.351162 kernel: SCSI subsystem initialized Apr 30 12:52:40.362109 kernel: Loading iSCSI transport class v2.0-870. Apr 30 12:52:40.379116 kernel: iscsi: registered transport (tcp) Apr 30 12:52:40.404539 kernel: iscsi: registered transport (qla4xxx) Apr 30 12:52:40.404653 kernel: QLogic iSCSI HBA Driver Apr 30 12:52:40.473608 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 30 12:52:40.481486 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 30 12:52:40.531347 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 30 12:52:40.531490 kernel: device-mapper: uevent: version 1.0.3 Apr 30 12:52:40.532175 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 30 12:52:40.587133 kernel: raid6: avx2x4 gen() 17600 MB/s Apr 30 12:52:40.605117 kernel: raid6: avx2x2 gen() 20638 MB/s Apr 30 12:52:40.624486 kernel: raid6: avx2x1 gen() 17693 MB/s Apr 30 12:52:40.624807 kernel: raid6: using algorithm avx2x2 gen() 20638 MB/s Apr 30 12:52:40.643529 kernel: raid6: .... xor() 17200 MB/s, rmw enabled Apr 30 12:52:40.643642 kernel: raid6: using avx2x2 recovery algorithm Apr 30 12:52:40.670119 kernel: xor: automatically using best checksumming function avx Apr 30 12:52:40.853118 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 30 12:52:40.869451 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 30 12:52:40.876354 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:52:40.908807 systemd-udevd[404]: Using default interface naming scheme 'v255'. Apr 30 12:52:40.919495 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:52:40.928323 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 30 12:52:40.959769 dracut-pre-trigger[411]: rd.md=0: removing MD RAID activation Apr 30 12:52:41.001903 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 12:52:41.009396 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 12:52:41.091850 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:52:41.103322 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 30 12:52:41.131920 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 30 12:52:41.134348 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 12:52:41.137807 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:52:41.138542 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 12:52:41.148655 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 30 12:52:41.182934 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 30 12:52:41.211116 kernel: virtio_blk virtio4: 1/0/0 default/read/poll queues Apr 30 12:52:41.299158 kernel: virtio_blk virtio4: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Apr 30 12:52:41.299385 kernel: scsi host0: Virtio SCSI HBA Apr 30 12:52:41.299579 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 30 12:52:41.299600 kernel: GPT:9289727 != 125829119 Apr 30 12:52:41.299618 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 30 12:52:41.299635 kernel: GPT:9289727 != 125829119 Apr 30 12:52:41.299669 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 30 12:52:41.299686 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 12:52:41.299704 kernel: libata version 3.00 loaded. Apr 30 12:52:41.299721 kernel: ata_piix 0000:00:01.1: version 2.13 Apr 30 12:52:41.309780 kernel: scsi host1: ata_piix Apr 30 12:52:41.310366 kernel: scsi host2: ata_piix Apr 30 12:52:41.310568 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc1e0 irq 14 Apr 30 12:52:41.310583 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc1e8 irq 15 Apr 30 12:52:41.310611 kernel: ACPI: bus type USB registered Apr 30 12:52:41.310625 kernel: virtio_blk virtio5: 1/0/0 default/read/poll queues Apr 30 12:52:41.319663 kernel: usbcore: registered new interface driver usbfs Apr 30 12:52:41.319707 kernel: cryptd: max_cpu_qlen set to 1000 Apr 30 12:52:41.319734 kernel: virtio_blk virtio5: [vdb] 932 512-byte logical blocks (477 kB/466 KiB) Apr 30 12:52:41.320766 kernel: usbcore: registered new interface driver hub Apr 30 12:52:41.320803 kernel: usbcore: registered new device driver usb Apr 30 12:52:41.333353 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 12:52:41.334320 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:52:41.335940 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:52:41.337503 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:52:41.337609 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:52:41.339919 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:52:41.346480 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:52:41.347808 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:52:41.430596 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:52:41.439508 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 30 12:52:41.509115 kernel: AVX2 version of gcm_enc/dec engaged. Apr 30 12:52:41.517141 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:52:41.530093 kernel: AES CTR mode by8 optimization enabled Apr 30 12:52:41.556228 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (452) Apr 30 12:52:41.586170 kernel: BTRFS: device fsid 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 devid 1 transid 40 /dev/vda3 scanned by (udev-worker) (447) Apr 30 12:52:41.584925 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Apr 30 12:52:41.631846 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Apr 30 12:52:41.642140 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Apr 30 12:52:41.650785 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Apr 30 12:52:41.651074 kernel: uhci_hcd 0000:00:01.2: detected 2 ports Apr 30 12:52:41.651301 kernel: uhci_hcd 0000:00:01.2: irq 11, io port 0x0000c180 Apr 30 12:52:41.651548 kernel: hub 1-0:1.0: USB hub found Apr 30 12:52:41.651808 kernel: hub 1-0:1.0: 2 ports detected Apr 30 12:52:41.651106 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 30 12:52:41.664947 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Apr 30 12:52:41.666020 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Apr 30 12:52:41.680654 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 30 12:52:41.691006 disk-uuid[548]: Primary Header is updated. Apr 30 12:52:41.691006 disk-uuid[548]: Secondary Entries is updated. Apr 30 12:52:41.691006 disk-uuid[548]: Secondary Header is updated. Apr 30 12:52:41.698094 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 12:52:41.706110 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 12:52:42.713120 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 30 12:52:42.714570 disk-uuid[549]: The operation has completed successfully. Apr 30 12:52:42.797556 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 30 12:52:42.797790 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 30 12:52:42.847511 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 30 12:52:42.863402 sh[560]: Success Apr 30 12:52:42.884506 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Apr 30 12:52:42.988623 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 30 12:52:42.989707 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 30 12:52:42.994325 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 30 12:52:43.037132 kernel: BTRFS info (device dm-0): first mount of filesystem 0778af4c-f6f8-4118-a0d2-fb24d73f5df4 Apr 30 12:52:43.037263 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:52:43.037296 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 30 12:52:43.041069 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 30 12:52:43.041210 kernel: BTRFS info (device dm-0): using free space tree Apr 30 12:52:43.055697 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 30 12:52:43.056902 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 30 12:52:43.063473 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 30 12:52:43.073824 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 30 12:52:43.101178 kernel: BTRFS info (device vda6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:52:43.104222 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:52:43.104393 kernel: BTRFS info (device vda6): using free space tree Apr 30 12:52:43.113137 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 12:52:43.122153 kernel: BTRFS info (device vda6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:52:43.127375 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 30 12:52:43.137490 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 30 12:52:43.309067 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 12:52:43.312925 ignition[650]: Ignition 2.20.0 Apr 30 12:52:43.312939 ignition[650]: Stage: fetch-offline Apr 30 12:52:43.312983 ignition[650]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:52:43.312996 ignition[650]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Apr 30 12:52:43.313159 ignition[650]: parsed url from cmdline: "" Apr 30 12:52:43.313164 ignition[650]: no config URL provided Apr 30 12:52:43.313171 ignition[650]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 12:52:43.313181 ignition[650]: no config at "/usr/lib/ignition/user.ign" Apr 30 12:52:43.313191 ignition[650]: failed to fetch config: resource requires networking Apr 30 12:52:43.313470 ignition[650]: Ignition finished successfully Apr 30 12:52:43.320395 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 12:52:43.322200 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 12:52:43.372988 systemd-networkd[749]: lo: Link UP Apr 30 12:52:43.373001 systemd-networkd[749]: lo: Gained carrier Apr 30 12:52:43.375862 systemd-networkd[749]: Enumeration completed Apr 30 12:52:43.376364 systemd-networkd[749]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Apr 30 12:52:43.376370 systemd-networkd[749]: eth0: Configuring with /usr/lib/systemd/network/yy-digitalocean.network. Apr 30 12:52:43.377269 systemd-networkd[749]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:52:43.377273 systemd-networkd[749]: eth1: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 30 12:52:43.378556 systemd-networkd[749]: eth0: Link UP Apr 30 12:52:43.378562 systemd-networkd[749]: eth0: Gained carrier Apr 30 12:52:43.378575 systemd-networkd[749]: eth0: found matching network '/usr/lib/systemd/network/yy-digitalocean.network', based on potentially unpredictable interface name. Apr 30 12:52:43.379006 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 12:52:43.381695 systemd[1]: Reached target network.target - Network. Apr 30 12:52:43.384312 systemd-networkd[749]: eth1: Link UP Apr 30 12:52:43.384319 systemd-networkd[749]: eth1: Gained carrier Apr 30 12:52:43.384336 systemd-networkd[749]: eth1: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 30 12:52:43.389265 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 30 12:52:43.400177 systemd-networkd[749]: eth0: DHCPv4 address 164.92.126.92/19, gateway 164.92.96.1 acquired from 169.254.169.253 Apr 30 12:52:43.407003 systemd-networkd[749]: eth1: DHCPv4 address 10.124.0.27/20 acquired from 169.254.169.253 Apr 30 12:52:43.416159 ignition[753]: Ignition 2.20.0 Apr 30 12:52:43.416175 ignition[753]: Stage: fetch Apr 30 12:52:43.416488 ignition[753]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:52:43.416618 ignition[753]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Apr 30 12:52:43.416852 ignition[753]: parsed url from cmdline: "" Apr 30 12:52:43.416859 ignition[753]: no config URL provided Apr 30 12:52:43.416868 ignition[753]: reading system config file "/usr/lib/ignition/user.ign" Apr 30 12:52:43.416892 ignition[753]: no config at "/usr/lib/ignition/user.ign" Apr 30 12:52:43.416929 ignition[753]: GET http://169.254.169.254/metadata/v1/user-data: attempt #1 Apr 30 12:52:43.434718 ignition[753]: GET result: OK Apr 30 12:52:43.434823 ignition[753]: parsing config with SHA512: 84a753788828a5bba0a9d5c1c4543045b566343f3442c0281fd74514e414e8bba39fd345600e0871e6b5e3610aa0226dc8bdb0f000b86ba53ddd62ffd1249222 Apr 30 12:52:43.440215 unknown[753]: fetched base config from "system" Apr 30 12:52:43.440233 unknown[753]: fetched base config from "system" Apr 30 12:52:43.440664 ignition[753]: fetch: fetch complete Apr 30 12:52:43.440244 unknown[753]: fetched user config from "digitalocean" Apr 30 12:52:43.440674 ignition[753]: fetch: fetch passed Apr 30 12:52:43.445105 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 30 12:52:43.440748 ignition[753]: Ignition finished successfully Apr 30 12:52:43.451475 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 30 12:52:43.489480 ignition[761]: Ignition 2.20.0 Apr 30 12:52:43.489505 ignition[761]: Stage: kargs Apr 30 12:52:43.489931 ignition[761]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:52:43.489957 ignition[761]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Apr 30 12:52:43.493335 ignition[761]: kargs: kargs passed Apr 30 12:52:43.493464 ignition[761]: Ignition finished successfully Apr 30 12:52:43.495254 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 30 12:52:43.502411 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 30 12:52:43.547862 ignition[767]: Ignition 2.20.0 Apr 30 12:52:43.547884 ignition[767]: Stage: disks Apr 30 12:52:43.548275 ignition[767]: no configs at "/usr/lib/ignition/base.d" Apr 30 12:52:43.548298 ignition[767]: no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Apr 30 12:52:43.549859 ignition[767]: disks: disks passed Apr 30 12:52:43.551640 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 30 12:52:43.549955 ignition[767]: Ignition finished successfully Apr 30 12:52:43.557387 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 30 12:52:43.558298 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 30 12:52:43.559556 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 12:52:43.560944 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 12:52:43.562248 systemd[1]: Reached target basic.target - Basic System. Apr 30 12:52:43.568772 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 30 12:52:43.598758 systemd-fsck[775]: ROOT: clean, 14/553520 files, 52654/553472 blocks Apr 30 12:52:43.603164 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 30 12:52:43.608294 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 30 12:52:43.748081 kernel: EXT4-fs (vda9): mounted filesystem 59d16236-967d-47d1-a9bd-4b055a17ab77 r/w with ordered data mode. Quota mode: none. Apr 30 12:52:43.749712 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 30 12:52:43.751883 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 30 12:52:43.768363 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 12:52:43.772690 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 30 12:52:43.776396 systemd[1]: Starting flatcar-afterburn-network.service - Flatcar Afterburn network service... Apr 30 12:52:43.790105 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (783) Apr 30 12:52:43.796086 kernel: BTRFS info (device vda6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:52:43.800464 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:52:43.800616 kernel: BTRFS info (device vda6): using free space tree Apr 30 12:52:43.806826 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Apr 30 12:52:43.808484 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 30 12:52:43.815678 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 12:52:43.821151 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 12:52:43.822372 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 30 12:52:43.832743 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 30 12:52:43.842800 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 12:52:43.902664 coreos-metadata[786]: Apr 30 12:52:43.902 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Apr 30 12:52:43.915802 coreos-metadata[785]: Apr 30 12:52:43.915 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Apr 30 12:52:43.918234 coreos-metadata[786]: Apr 30 12:52:43.917 INFO Fetch successful Apr 30 12:52:43.923132 coreos-metadata[786]: Apr 30 12:52:43.923 INFO wrote hostname ci-4230.1.1-b-56129568f5 to /sysroot/etc/hostname Apr 30 12:52:43.925606 initrd-setup-root[813]: cut: /sysroot/etc/passwd: No such file or directory Apr 30 12:52:43.924813 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Apr 30 12:52:43.930333 coreos-metadata[785]: Apr 30 12:52:43.930 INFO Fetch successful Apr 30 12:52:43.935849 initrd-setup-root[821]: cut: /sysroot/etc/group: No such file or directory Apr 30 12:52:43.940626 systemd[1]: flatcar-afterburn-network.service: Deactivated successfully. Apr 30 12:52:43.941277 systemd[1]: Finished flatcar-afterburn-network.service - Flatcar Afterburn network service. Apr 30 12:52:43.946751 initrd-setup-root[829]: cut: /sysroot/etc/shadow: No such file or directory Apr 30 12:52:43.952265 initrd-setup-root[836]: cut: /sysroot/etc/gshadow: No such file or directory Apr 30 12:52:44.071937 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 30 12:52:44.078230 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 30 12:52:44.080286 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 30 12:52:44.093206 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 30 12:52:44.094447 kernel: BTRFS info (device vda6): last unmount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:52:44.129592 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 30 12:52:44.132879 ignition[903]: INFO : Ignition 2.20.0 Apr 30 12:52:44.132879 ignition[903]: INFO : Stage: mount Apr 30 12:52:44.134806 ignition[903]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:52:44.134806 ignition[903]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Apr 30 12:52:44.134806 ignition[903]: INFO : mount: mount passed Apr 30 12:52:44.134806 ignition[903]: INFO : Ignition finished successfully Apr 30 12:52:44.135873 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 30 12:52:44.143257 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 30 12:52:44.166411 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 30 12:52:44.181612 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (916) Apr 30 12:52:44.181693 kernel: BTRFS info (device vda6): first mount of filesystem 70902d85-577c-4d48-8616-61ed6d6784d1 Apr 30 12:52:44.183604 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 30 12:52:44.185282 kernel: BTRFS info (device vda6): using free space tree Apr 30 12:52:44.190103 kernel: BTRFS info (device vda6): auto enabling async discard Apr 30 12:52:44.193621 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 30 12:52:44.225336 ignition[933]: INFO : Ignition 2.20.0 Apr 30 12:52:44.225336 ignition[933]: INFO : Stage: files Apr 30 12:52:44.226951 ignition[933]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:52:44.226951 ignition[933]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Apr 30 12:52:44.226951 ignition[933]: DEBUG : files: compiled without relabeling support, skipping Apr 30 12:52:44.229906 ignition[933]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 30 12:52:44.229906 ignition[933]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 30 12:52:44.232397 ignition[933]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 30 12:52:44.233570 ignition[933]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 30 12:52:44.233570 ignition[933]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 30 12:52:44.233216 unknown[933]: wrote ssh authorized keys file for user: core Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Apr 30 12:52:44.236596 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-x86-64.raw: attempt #1 Apr 30 12:52:44.568416 systemd-networkd[749]: eth0: Gained IPv6LL Apr 30 12:52:44.760378 systemd-networkd[749]: eth1: Gained IPv6LL Apr 30 12:52:44.942042 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Apr 30 12:52:45.294539 ignition[933]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Apr 30 12:52:45.296450 ignition[933]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 30 12:52:45.296450 ignition[933]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 30 12:52:45.296450 ignition[933]: INFO : files: files passed Apr 30 12:52:45.296450 ignition[933]: INFO : Ignition finished successfully Apr 30 12:52:45.296909 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 30 12:52:45.312107 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 30 12:52:45.315638 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 30 12:52:45.322892 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 30 12:52:45.323109 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 30 12:52:45.343736 initrd-setup-root-after-ignition[961]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:52:45.343736 initrd-setup-root-after-ignition[961]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:52:45.348128 initrd-setup-root-after-ignition[965]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 30 12:52:45.350960 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 12:52:45.352339 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 30 12:52:45.363362 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 30 12:52:45.411710 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 30 12:52:45.411885 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 30 12:52:45.413879 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 30 12:52:45.414773 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 30 12:52:45.416221 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 30 12:52:45.428045 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 30 12:52:45.452134 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 12:52:45.460543 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 30 12:52:45.490526 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:52:45.491696 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:52:45.493549 systemd[1]: Stopped target timers.target - Timer Units. Apr 30 12:52:45.494863 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 30 12:52:45.495184 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 30 12:52:45.497224 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 30 12:52:45.499000 systemd[1]: Stopped target basic.target - Basic System. Apr 30 12:52:45.500362 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 30 12:52:45.501719 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 30 12:52:45.503262 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 30 12:52:45.504971 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 30 12:52:45.506411 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 30 12:52:45.507916 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 30 12:52:45.509623 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 30 12:52:45.510939 systemd[1]: Stopped target swap.target - Swaps. Apr 30 12:52:45.512123 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 30 12:52:45.512398 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 30 12:52:45.514113 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:52:45.515169 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:52:45.516785 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 30 12:52:45.517004 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:52:45.518503 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 30 12:52:45.518764 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 30 12:52:45.520812 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 30 12:52:45.521199 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 30 12:52:45.522922 systemd[1]: ignition-files.service: Deactivated successfully. Apr 30 12:52:45.523285 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 30 12:52:45.525237 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Apr 30 12:52:45.525531 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Apr 30 12:52:45.540610 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 30 12:52:45.541515 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 30 12:52:45.541843 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:52:45.554235 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 30 12:52:45.556559 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 30 12:52:45.556930 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:52:45.560542 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 30 12:52:45.560830 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 30 12:52:45.570547 ignition[985]: INFO : Ignition 2.20.0 Apr 30 12:52:45.570547 ignition[985]: INFO : Stage: umount Apr 30 12:52:45.577907 ignition[985]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 30 12:52:45.577907 ignition[985]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/digitalocean" Apr 30 12:52:45.577907 ignition[985]: INFO : umount: umount passed Apr 30 12:52:45.577907 ignition[985]: INFO : Ignition finished successfully Apr 30 12:52:45.575665 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 30 12:52:45.575838 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 30 12:52:45.586927 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 30 12:52:45.587151 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 30 12:52:45.592825 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 30 12:52:45.592947 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 30 12:52:45.595609 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 30 12:52:45.595737 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 30 12:52:45.596737 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 30 12:52:45.596831 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 30 12:52:45.597448 systemd[1]: Stopped target network.target - Network. Apr 30 12:52:45.597948 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 30 12:52:45.598043 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 30 12:52:45.600231 systemd[1]: Stopped target paths.target - Path Units. Apr 30 12:52:45.601018 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 30 12:52:45.608200 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:52:45.609120 systemd[1]: Stopped target slices.target - Slice Units. Apr 30 12:52:45.609740 systemd[1]: Stopped target sockets.target - Socket Units. Apr 30 12:52:45.611415 systemd[1]: iscsid.socket: Deactivated successfully. Apr 30 12:52:45.611501 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 30 12:52:45.615288 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 30 12:52:45.615384 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 30 12:52:45.619368 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 30 12:52:45.619480 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 30 12:52:45.620435 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 30 12:52:45.620557 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 30 12:52:45.621855 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 30 12:52:45.635402 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 30 12:52:45.646146 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 30 12:52:45.647732 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 30 12:52:45.647932 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 30 12:52:45.654656 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Apr 30 12:52:45.655261 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 30 12:52:45.655463 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 30 12:52:45.659432 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Apr 30 12:52:45.659923 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 30 12:52:45.660207 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 30 12:52:45.663767 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 30 12:52:45.663858 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:52:45.665359 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 30 12:52:45.665444 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 30 12:52:45.674375 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 30 12:52:45.676495 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 30 12:52:45.676658 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 30 12:52:45.680651 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 30 12:52:45.680776 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:52:45.682320 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 30 12:52:45.682408 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 30 12:52:45.683337 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 30 12:52:45.683420 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:52:45.685405 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:52:45.691968 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Apr 30 12:52:45.693187 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:52:45.710343 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 30 12:52:45.711305 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:52:45.713951 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 30 12:52:45.714306 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 30 12:52:45.717686 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 30 12:52:45.717798 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 30 12:52:45.719359 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 30 12:52:45.719441 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:52:45.720823 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 30 12:52:45.720932 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 30 12:52:45.722812 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 30 12:52:45.722892 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 30 12:52:45.724003 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 30 12:52:45.724113 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 30 12:52:45.731567 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 30 12:52:45.734489 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 30 12:52:45.734629 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:52:45.736201 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:52:45.736311 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:52:45.739854 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Apr 30 12:52:45.739987 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Apr 30 12:52:45.754839 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 30 12:52:45.755014 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 30 12:52:45.757621 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 30 12:52:45.767438 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 30 12:52:45.784743 systemd[1]: Switching root. Apr 30 12:52:45.819627 systemd-journald[183]: Journal stopped Apr 30 12:52:47.831725 systemd-journald[183]: Received SIGTERM from PID 1 (systemd). Apr 30 12:52:47.831848 kernel: SELinux: policy capability network_peer_controls=1 Apr 30 12:52:47.831892 kernel: SELinux: policy capability open_perms=1 Apr 30 12:52:47.831918 kernel: SELinux: policy capability extended_socket_class=1 Apr 30 12:52:47.831950 kernel: SELinux: policy capability always_check_network=0 Apr 30 12:52:47.831981 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 30 12:52:47.832033 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 30 12:52:47.832101 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 30 12:52:47.832133 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 30 12:52:47.832168 kernel: audit: type=1403 audit(1746017566.011:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 30 12:52:47.832207 systemd[1]: Successfully loaded SELinux policy in 53.712ms. Apr 30 12:52:47.832269 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 21.310ms. Apr 30 12:52:47.832315 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Apr 30 12:52:47.832349 systemd[1]: Detected virtualization kvm. Apr 30 12:52:47.832384 systemd[1]: Detected architecture x86-64. Apr 30 12:52:47.832411 systemd[1]: Detected first boot. Apr 30 12:52:47.832458 systemd[1]: Hostname set to . Apr 30 12:52:47.832492 systemd[1]: Initializing machine ID from VM UUID. Apr 30 12:52:47.832519 zram_generator::config[1031]: No configuration found. Apr 30 12:52:47.832562 kernel: Guest personality initialized and is inactive Apr 30 12:52:47.832596 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Apr 30 12:52:47.832630 kernel: Initialized host personality Apr 30 12:52:47.832662 kernel: NET: Registered PF_VSOCK protocol family Apr 30 12:52:47.832719 systemd[1]: Populated /etc with preset unit settings. Apr 30 12:52:47.832753 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Apr 30 12:52:47.832785 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 30 12:52:47.832819 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 30 12:52:47.832845 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 30 12:52:47.832868 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 30 12:52:47.832888 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 30 12:52:47.832907 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 30 12:52:47.832931 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 30 12:52:47.832954 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 30 12:52:47.832978 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 30 12:52:47.832999 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 30 12:52:47.833031 systemd[1]: Created slice user.slice - User and Session Slice. Apr 30 12:52:47.835415 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 30 12:52:47.835490 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 30 12:52:47.835518 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 30 12:52:47.835546 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 30 12:52:47.835575 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 30 12:52:47.835613 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 30 12:52:47.835640 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 30 12:52:47.835667 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 30 12:52:47.835695 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 30 12:52:47.835721 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 30 12:52:47.835747 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 30 12:52:47.835773 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 30 12:52:47.835799 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 30 12:52:47.835839 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 30 12:52:47.835875 systemd[1]: Reached target slices.target - Slice Units. Apr 30 12:52:47.835918 systemd[1]: Reached target swap.target - Swaps. Apr 30 12:52:47.835938 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 30 12:52:47.835971 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 30 12:52:47.835998 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Apr 30 12:52:47.836025 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 30 12:52:47.836070 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 30 12:52:47.836097 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 30 12:52:47.836124 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 30 12:52:47.836151 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 30 12:52:47.836188 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 30 12:52:47.836216 systemd[1]: Mounting media.mount - External Media Directory... Apr 30 12:52:47.836243 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:47.836270 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 30 12:52:47.836299 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 30 12:52:47.836328 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 30 12:52:47.836356 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). Apr 30 12:52:47.836383 systemd[1]: Reached target machines.target - Containers. Apr 30 12:52:47.836425 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 30 12:52:47.836528 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:52:47.836550 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 30 12:52:47.836571 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 30 12:52:47.836606 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:52:47.836632 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 12:52:47.836652 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 12:52:47.836672 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 30 12:52:47.836691 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:52:47.836720 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 30 12:52:47.836740 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 30 12:52:47.836762 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 30 12:52:47.836782 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 30 12:52:47.836803 systemd[1]: Stopped systemd-fsck-usr.service. Apr 30 12:52:47.836825 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Apr 30 12:52:47.836846 kernel: fuse: init (API version 7.39) Apr 30 12:52:47.836867 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 30 12:52:47.836892 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 30 12:52:47.836927 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 30 12:52:47.836956 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 30 12:52:47.836987 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Apr 30 12:52:47.837014 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 30 12:52:47.837040 systemd[1]: verity-setup.service: Deactivated successfully. Apr 30 12:52:47.841924 systemd[1]: Stopped verity-setup.service. Apr 30 12:52:47.841964 kernel: ACPI: bus type drm_connector registered Apr 30 12:52:47.842000 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:47.846966 kernel: loop: module loaded Apr 30 12:52:47.847095 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 30 12:52:47.847125 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 30 12:52:47.847147 systemd[1]: Mounted media.mount - External Media Directory. Apr 30 12:52:47.847167 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 30 12:52:47.847188 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 30 12:52:47.847209 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 30 12:52:47.847230 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 30 12:52:47.847251 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 30 12:52:47.847271 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 30 12:52:47.847298 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:52:47.847318 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:52:47.847338 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 12:52:47.847374 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 12:52:47.847393 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 12:52:47.847412 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 12:52:47.847432 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 30 12:52:47.847451 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 30 12:52:47.847470 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:52:47.847504 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:52:47.847529 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 30 12:52:47.847563 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 30 12:52:47.847594 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 30 12:52:47.847616 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 30 12:52:47.847645 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 30 12:52:47.847665 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 30 12:52:47.847689 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Apr 30 12:52:47.847719 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 30 12:52:47.847748 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 30 12:52:47.847773 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:52:47.847796 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 30 12:52:47.847887 systemd-journald[1108]: Collecting audit messages is disabled. Apr 30 12:52:47.847930 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 12:52:47.847950 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 30 12:52:47.847969 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 12:52:47.847996 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 30 12:52:47.848019 systemd-journald[1108]: Journal started Apr 30 12:52:47.848077 systemd-journald[1108]: Runtime Journal (/run/log/journal/bb19098ad7284cea9403407dbb8ecdb2) is 4.9M, max 39.3M, 34.4M free. Apr 30 12:52:47.222638 systemd[1]: Queued start job for default target multi-user.target. Apr 30 12:52:47.234794 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Apr 30 12:52:47.235578 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 30 12:52:47.873213 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 30 12:52:47.873275 systemd[1]: Started systemd-journald.service - Journal Service. Apr 30 12:52:47.867798 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 30 12:52:47.869116 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 30 12:52:47.871537 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Apr 30 12:52:47.872640 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 30 12:52:47.874566 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 30 12:52:47.875508 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 30 12:52:47.877886 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 30 12:52:47.895479 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 30 12:52:47.921505 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 30 12:52:47.924780 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 30 12:52:47.934025 kernel: loop0: detected capacity change from 0 to 138176 Apr 30 12:52:47.936345 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 30 12:52:47.947328 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Apr 30 12:52:47.957458 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 30 12:52:47.969026 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 30 12:52:47.988088 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 30 12:52:47.988220 systemd-journald[1108]: Time spent on flushing to /var/log/journal/bb19098ad7284cea9403407dbb8ecdb2 is 67.937ms for 991 entries. Apr 30 12:52:47.988220 systemd-journald[1108]: System Journal (/var/log/journal/bb19098ad7284cea9403407dbb8ecdb2) is 8M, max 195.6M, 187.6M free. Apr 30 12:52:48.072636 systemd-journald[1108]: Received client request to flush runtime journal. Apr 30 12:52:48.072724 kernel: loop1: detected capacity change from 0 to 147912 Apr 30 12:52:47.997335 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 30 12:52:48.076386 kernel: loop2: detected capacity change from 0 to 8 Apr 30 12:52:48.038362 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Apr 30 12:52:48.048990 udevadm[1166]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Apr 30 12:52:48.075375 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 30 12:52:48.110080 kernel: loop3: detected capacity change from 0 to 210664 Apr 30 12:52:48.111278 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 30 12:52:48.122101 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 30 12:52:48.181090 kernel: loop4: detected capacity change from 0 to 138176 Apr 30 12:52:48.185481 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. Apr 30 12:52:48.185961 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. Apr 30 12:52:48.210473 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 30 12:52:48.225109 kernel: loop5: detected capacity change from 0 to 147912 Apr 30 12:52:48.239824 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 30 12:52:48.254086 kernel: loop6: detected capacity change from 0 to 8 Apr 30 12:52:48.260107 kernel: loop7: detected capacity change from 0 to 210664 Apr 30 12:52:48.280690 (sd-merge)[1181]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-digitalocean'. Apr 30 12:52:48.281706 (sd-merge)[1181]: Merged extensions into '/usr'. Apr 30 12:52:48.297244 systemd[1]: Reload requested from client PID 1138 ('systemd-sysext') (unit systemd-sysext.service)... Apr 30 12:52:48.297410 systemd[1]: Reloading... Apr 30 12:52:48.475700 zram_generator::config[1209]: No configuration found. Apr 30 12:52:48.621032 ldconfig[1134]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 30 12:52:48.782554 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. Apr 30 12:52:48.927047 systemd[1]: Reloading finished in 626 ms. Apr 30 12:52:48.954205 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 30 12:52:48.959268 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 30 12:52:48.968392 systemd[1]: Starting ensure-sysext.service... Apr 30 12:52:48.978602 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 30 12:52:49.002860 systemd[1]: Reload requested from client PID 1253 ('systemctl') (unit ensure-sysext.service)... Apr 30 12:52:49.002878 systemd[1]: Reloading... Apr 30 12:52:49.034254 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 30 12:52:49.034704 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 30 12:52:49.037031 systemd-tmpfiles[1254]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 30 12:52:49.038239 systemd-tmpfiles[1254]: ACLs are not supported, ignoring. Apr 30 12:52:49.038427 systemd-tmpfiles[1254]: ACLs are not supported, ignoring. Apr 30 12:52:49.045343 systemd-tmpfiles[1254]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 12:52:49.045360 systemd-tmpfiles[1254]: Skipping /boot Apr 30 12:52:49.090647 systemd-tmpfiles[1254]: Detected autofs mount point /boot during canonicalization of boot. Apr 30 12:52:49.090665 systemd-tmpfiles[1254]: Skipping /boot Apr 30 12:52:49.144093 zram_generator::config[1283]: No configuration found. Apr 30 12:52:49.396520 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. Apr 30 12:52:49.538244 systemd[1]: Reloading finished in 534 ms. Apr 30 12:52:49.555341 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 30 12:52:49.571277 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 30 12:52:49.588681 systemd[1]: Starting audit-rules.service - Load Audit Rules... Apr 30 12:52:49.593464 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 30 12:52:49.604528 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 30 12:52:49.616515 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 30 12:52:49.619521 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 30 12:52:49.623975 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 30 12:52:49.634305 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:49.634743 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:52:49.646553 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:52:49.650011 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 12:52:49.659752 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:52:49.662370 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:52:49.662653 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Apr 30 12:52:49.662876 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:49.671783 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:49.672203 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:52:49.672535 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:52:49.672629 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Apr 30 12:52:49.672729 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:49.680909 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:49.682723 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:52:49.691492 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 30 12:52:49.693774 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:52:49.694025 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Apr 30 12:52:49.694716 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:49.701152 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:52:49.701431 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:52:49.703588 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 30 12:52:49.714484 systemd[1]: Finished ensure-sysext.service. Apr 30 12:52:49.715987 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 30 12:52:49.717081 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 30 12:52:49.737352 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 12:52:49.737948 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 12:52:49.739392 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:52:49.739697 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:52:49.745689 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 30 12:52:49.750308 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 30 12:52:49.753570 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 12:52:49.753700 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 12:52:49.763378 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Apr 30 12:52:49.766499 augenrules[1366]: No rules Apr 30 12:52:49.766329 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 30 12:52:49.772636 systemd-udevd[1338]: Using default interface naming scheme 'v255'. Apr 30 12:52:49.776432 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 30 12:52:49.777042 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). Apr 30 12:52:49.777538 systemd[1]: audit-rules.service: Deactivated successfully. Apr 30 12:52:49.778144 systemd[1]: Finished audit-rules.service - Load Audit Rules. Apr 30 12:52:49.816174 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 30 12:52:49.817768 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 30 12:52:49.828988 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 30 12:52:49.907299 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 30 12:52:49.922461 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 30 12:52:50.138755 systemd[1]: Condition check resulted in dev-disk-by\x2dlabel-config\x2d2.device - /dev/disk/by-label/config-2 being skipped. Apr 30 12:52:50.149242 systemd[1]: Mounting media-configdrive.mount - /media/configdrive... Apr 30 12:52:50.151164 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:50.151415 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 30 12:52:50.158866 systemd-networkd[1381]: lo: Link UP Apr 30 12:52:50.158879 systemd-networkd[1381]: lo: Gained carrier Apr 30 12:52:50.159697 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 30 12:52:50.161327 systemd-networkd[1381]: Enumeration completed Apr 30 12:52:50.169630 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 30 12:52:50.176410 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 30 12:52:50.177312 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 30 12:52:50.177378 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Apr 30 12:52:50.177436 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). Apr 30 12:52:50.177470 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 30 12:52:50.177809 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 30 12:52:50.180023 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 30 12:52:50.181016 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 30 12:52:50.193321 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Apr 30 12:52:50.197309 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 30 12:52:50.199087 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 30 12:52:50.199651 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 30 12:52:50.200570 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 30 12:52:50.229223 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 30 12:52:50.229335 systemd-networkd[1381]: eth0: Configuring with /run/systemd/network/10-6a:23:22:af:66:8f.network. Apr 30 12:52:50.229793 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 30 12:52:50.231429 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 30 12:52:50.234809 systemd-networkd[1381]: eth0: Link UP Apr 30 12:52:50.234823 systemd-networkd[1381]: eth0: Gained carrier Apr 30 12:52:50.251099 kernel: ISO 9660 Extensions: RRIP_1991A Apr 30 12:52:50.252343 systemd[1]: Mounted media-configdrive.mount - /media/configdrive. Apr 30 12:52:50.264121 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1387) Apr 30 12:52:50.269998 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Apr 30 12:52:50.275508 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Apr 30 12:52:50.276586 systemd[1]: Reached target time-set.target - System Time Set. Apr 30 12:52:50.295666 systemd-timesyncd[1364]: Contacted time server 149.248.12.167:123 (0.flatcar.pool.ntp.org). Apr 30 12:52:50.296453 systemd-resolved[1337]: Positive Trust Anchors: Apr 30 12:52:50.296468 systemd-resolved[1337]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 30 12:52:50.296508 systemd-resolved[1337]: 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 Apr 30 12:52:50.297114 systemd-timesyncd[1364]: Initial clock synchronization to Wed 2025-04-30 12:52:50.475050 UTC. Apr 30 12:52:50.307036 systemd-resolved[1337]: Using system hostname 'ci-4230.1.1-b-56129568f5'. Apr 30 12:52:50.309816 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 30 12:52:50.310944 systemd[1]: Reached target network.target - Network. Apr 30 12:52:50.311492 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 30 12:52:50.317730 systemd-networkd[1381]: eth1: Configuring with /run/systemd/network/10-2a:c2:47:d7:f2:43.network. Apr 30 12:52:50.319851 systemd-networkd[1381]: eth1: Link UP Apr 30 12:52:50.321185 systemd-networkd[1381]: eth1: Gained carrier Apr 30 12:52:50.324106 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Apr 30 12:52:50.330092 kernel: ACPI: button: Power Button [PWRF] Apr 30 12:52:50.377086 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Apr 30 12:52:50.388851 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 30 12:52:50.402831 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 30 12:52:50.403166 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Apr 30 12:52:50.436888 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 30 12:52:50.468080 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Apr 30 12:52:50.470086 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Apr 30 12:52:50.481566 kernel: Console: switching to colour dummy device 80x25 Apr 30 12:52:50.481676 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Apr 30 12:52:50.481704 kernel: [drm] features: -context_init Apr 30 12:52:50.486089 kernel: [drm] number of scanouts: 1 Apr 30 12:52:50.487094 kernel: [drm] number of cap sets: 0 Apr 30 12:52:50.491085 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Apr 30 12:52:50.499099 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Apr 30 12:52:50.502217 kernel: Console: switching to colour frame buffer device 128x48 Apr 30 12:52:50.521091 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Apr 30 12:52:50.541539 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:52:50.562950 kernel: mousedev: PS/2 mouse device common for all mice Apr 30 12:52:50.620439 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 30 12:52:50.620946 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:52:50.637356 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 30 12:52:50.691192 kernel: EDAC MC: Ver: 3.0.0 Apr 30 12:52:50.714973 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 30 12:52:50.726203 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 30 12:52:50.745448 lvm[1440]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 12:52:50.752217 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 30 12:52:50.787585 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 30 12:52:50.789274 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 30 12:52:50.789482 systemd[1]: Reached target sysinit.target - System Initialization. Apr 30 12:52:50.789722 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 30 12:52:50.789894 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 30 12:52:50.790257 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 30 12:52:50.790470 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 30 12:52:50.790561 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 30 12:52:50.790661 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 30 12:52:50.790704 systemd[1]: Reached target paths.target - Path Units. Apr 30 12:52:50.790785 systemd[1]: Reached target timers.target - Timer Units. Apr 30 12:52:50.793853 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 30 12:52:50.798432 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 30 12:52:50.805292 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Apr 30 12:52:50.807630 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Apr 30 12:52:50.808265 systemd[1]: Reached target ssh-access.target - SSH Access Available. Apr 30 12:52:50.822355 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 30 12:52:50.823923 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Apr 30 12:52:50.837383 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 30 12:52:50.838946 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 30 12:52:50.842324 systemd[1]: Reached target sockets.target - Socket Units. Apr 30 12:52:50.842813 systemd[1]: Reached target basic.target - Basic System. Apr 30 12:52:50.844760 lvm[1447]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 30 12:52:50.845216 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 30 12:52:50.845268 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 30 12:52:50.852477 systemd[1]: Starting containerd.service - containerd container runtime... Apr 30 12:52:50.867395 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 30 12:52:50.877343 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 30 12:52:50.880711 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 30 12:52:50.892334 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 30 12:52:50.893755 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). Apr 30 12:52:50.899346 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 30 12:52:50.912028 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 30 12:52:50.918117 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 30 12:52:50.918723 jq[1451]: false Apr 30 12:52:50.937307 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 30 12:52:50.941918 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 30 12:52:50.942819 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 30 12:52:50.951400 systemd[1]: Starting update-engine.service - Update Engine... Apr 30 12:52:50.961280 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 30 12:52:50.965913 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 30 12:52:50.973045 dbus-daemon[1450]: [system] SELinux support is enabled Apr 30 12:52:50.975311 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 30 12:52:50.982957 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 30 12:52:50.984425 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 30 12:52:50.984959 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 30 12:52:50.986154 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 30 12:52:50.988624 update_engine[1460]: I20250430 12:52:50.988518 1460 main.cc:92] Flatcar Update Engine starting Apr 30 12:52:50.990443 update_engine[1460]: I20250430 12:52:50.990381 1460 update_check_scheduler.cc:74] Next update check in 9m30s Apr 30 12:52:50.998709 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). Apr 30 12:52:50.998788 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 30 12:52:51.004048 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). Apr 30 12:52:51.004251 systemd[1]: user-configdrive.service - Load cloud-config from /media/configdrive was skipped because of an unmet condition check (ConditionKernelCommandLine=!flatcar.oem.id=digitalocean). Apr 30 12:52:51.004287 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 30 12:52:51.007192 systemd[1]: Started update-engine.service - Update Engine. Apr 30 12:52:51.017479 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 30 12:52:51.046439 jq[1462]: true Apr 30 12:52:51.049472 extend-filesystems[1452]: Found loop4 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found loop5 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found loop6 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found loop7 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda1 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda2 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda3 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found usr Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda4 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda6 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda7 Apr 30 12:52:51.053044 extend-filesystems[1452]: Found vda9 Apr 30 12:52:51.053044 extend-filesystems[1452]: Checking size of /dev/vda9 Apr 30 12:52:51.102285 coreos-metadata[1449]: Apr 30 12:52:51.057 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Apr 30 12:52:51.102285 coreos-metadata[1449]: Apr 30 12:52:51.073 INFO Fetch successful Apr 30 12:52:51.085157 (ntainerd)[1477]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 30 12:52:51.103186 systemd[1]: motdgen.service: Deactivated successfully. Apr 30 12:52:51.103514 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 30 12:52:51.122619 extend-filesystems[1452]: Resized partition /dev/vda9 Apr 30 12:52:51.132148 extend-filesystems[1488]: resize2fs 1.47.1 (20-May-2024) Apr 30 12:52:51.144555 jq[1481]: true Apr 30 12:52:51.150719 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 15121403 blocks Apr 30 12:52:51.223683 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 30 12:52:51.225662 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 30 12:52:51.318892 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1390) Apr 30 12:52:51.331832 systemd-logind[1459]: New seat seat0. Apr 30 12:52:51.336049 systemd-logind[1459]: Watching system buttons on /dev/input/event1 (Power Button) Apr 30 12:52:51.336097 systemd-logind[1459]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Apr 30 12:52:51.341949 systemd[1]: Started systemd-logind.service - User Login Management. Apr 30 12:52:51.361946 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Apr 30 12:52:51.408756 extend-filesystems[1488]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Apr 30 12:52:51.408756 extend-filesystems[1488]: old_desc_blocks = 1, new_desc_blocks = 8 Apr 30 12:52:51.408756 extend-filesystems[1488]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Apr 30 12:52:51.423050 extend-filesystems[1452]: Resized filesystem in /dev/vda9 Apr 30 12:52:51.423050 extend-filesystems[1452]: Found vdb Apr 30 12:52:51.414512 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 30 12:52:51.414874 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 30 12:52:51.431689 bash[1508]: Updated "/home/core/.ssh/authorized_keys" Apr 30 12:52:51.435733 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 30 12:52:51.448617 systemd[1]: Starting sshkeys.service... Apr 30 12:52:51.480723 locksmithd[1472]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 30 12:52:51.486515 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Apr 30 12:52:51.499457 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Apr 30 12:52:51.604439 coreos-metadata[1521]: Apr 30 12:52:51.604 INFO Fetching http://169.254.169.254/metadata/v1.json: Attempt #1 Apr 30 12:52:51.618961 coreos-metadata[1521]: Apr 30 12:52:51.617 INFO Fetch successful Apr 30 12:52:51.643043 unknown[1521]: wrote ssh authorized keys file for user: core Apr 30 12:52:51.679800 update-ssh-keys[1525]: Updated "/home/core/.ssh/authorized_keys" Apr 30 12:52:51.680414 containerd[1477]: time="2025-04-30T12:52:51.680305805Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Apr 30 12:52:51.682296 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Apr 30 12:52:51.687300 systemd[1]: Finished sshkeys.service. Apr 30 12:52:51.752007 containerd[1477]: time="2025-04-30T12:52:51.751828168Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:52:51.758721 containerd[1477]: time="2025-04-30T12:52:51.758624435Z" 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.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:52:51.758721 containerd[1477]: time="2025-04-30T12:52:51.758693866Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 30 12:52:51.758721 containerd[1477]: time="2025-04-30T12:52:51.758725818Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 30 12:52:51.759035 containerd[1477]: time="2025-04-30T12:52:51.758991849Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 30 12:52:51.759157 containerd[1477]: time="2025-04-30T12:52:51.759036814Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 30 12:52:51.759206 containerd[1477]: time="2025-04-30T12:52:51.759159408Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:52:51.759206 containerd[1477]: time="2025-04-30T12:52:51.759184916Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:52:51.759543 containerd[1477]: time="2025-04-30T12:52:51.759490671Z" 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 Apr 30 12:52:51.759543 containerd[1477]: time="2025-04-30T12:52:51.759519647Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 30 12:52:51.759543 containerd[1477]: time="2025-04-30T12:52:51.759538262Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 30 12:52:51.759720 containerd[1477]: time="2025-04-30T12:52:51.759552102Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 30 12:52:51.759720 containerd[1477]: time="2025-04-30T12:52:51.759648667Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:52:51.759926 containerd[1477]: time="2025-04-30T12:52:51.759887751Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 30 12:52:51.761608 containerd[1477]: time="2025-04-30T12:52:51.761307830Z" 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 Apr 30 12:52:51.761608 containerd[1477]: time="2025-04-30T12:52:51.761343668Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 30 12:52:51.761608 containerd[1477]: time="2025-04-30T12:52:51.761506817Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 30 12:52:51.761608 containerd[1477]: time="2025-04-30T12:52:51.761572783Z" level=info msg="metadata content store policy set" policy=shared Apr 30 12:52:51.767823 containerd[1477]: time="2025-04-30T12:52:51.767767767Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768025829Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768057267Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768090619Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768115816Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768317278Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768645368Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768787196Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768809976Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768830342Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768849762Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768871087Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768895466Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768917642Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.769934 containerd[1477]: time="2025-04-30T12:52:51.768938246Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.768960309Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.768978516Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.768995064Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769044742Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769068116Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769127549Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769152378Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769169145Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769188256Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769204621Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769227186Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769329083Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769361193Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.770671 containerd[1477]: time="2025-04-30T12:52:51.769378984Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769396894Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769417223Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769438633Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769474380Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769494991Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769511550Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769575982Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769601613Z" 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 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769617052Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769636257Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769654337Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769677013Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769693358Z" level=info msg="NRI interface is disabled by configuration." Apr 30 12:52:51.771357 containerd[1477]: time="2025-04-30T12:52:51.769720125Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 30 12:52:51.773129 containerd[1477]: time="2025-04-30T12:52:51.772433456Z" 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}" Apr 30 12:52:51.773129 containerd[1477]: time="2025-04-30T12:52:51.772521980Z" level=info msg="Connect containerd service" Apr 30 12:52:51.773129 containerd[1477]: time="2025-04-30T12:52:51.772564808Z" level=info msg="using legacy CRI server" Apr 30 12:52:51.773129 containerd[1477]: time="2025-04-30T12:52:51.772575336Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 30 12:52:51.773129 containerd[1477]: time="2025-04-30T12:52:51.772766286Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 30 12:52:51.774800 containerd[1477]: time="2025-04-30T12:52:51.774354307Z" 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" Apr 30 12:52:51.774800 containerd[1477]: time="2025-04-30T12:52:51.774504901Z" level=info msg="Start subscribing containerd event" Apr 30 12:52:51.774800 containerd[1477]: time="2025-04-30T12:52:51.774556819Z" level=info msg="Start recovering state" Apr 30 12:52:51.774800 containerd[1477]: time="2025-04-30T12:52:51.774630178Z" level=info msg="Start event monitor" Apr 30 12:52:51.774800 containerd[1477]: time="2025-04-30T12:52:51.774642727Z" level=info msg="Start snapshots syncer" Apr 30 12:52:51.774800 containerd[1477]: time="2025-04-30T12:52:51.774654431Z" level=info msg="Start cni network conf syncer for default" Apr 30 12:52:51.774800 containerd[1477]: time="2025-04-30T12:52:51.774666478Z" level=info msg="Start streaming server" Apr 30 12:52:51.776850 containerd[1477]: time="2025-04-30T12:52:51.775687444Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 30 12:52:51.776850 containerd[1477]: time="2025-04-30T12:52:51.775748525Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 30 12:52:51.775932 systemd[1]: Started containerd.service - containerd container runtime. Apr 30 12:52:51.779065 containerd[1477]: time="2025-04-30T12:52:51.777968504Z" level=info msg="containerd successfully booted in 0.101204s" Apr 30 12:52:51.812833 sshd_keygen[1466]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 30 12:52:51.854510 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 30 12:52:51.870634 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 30 12:52:51.885156 systemd[1]: issuegen.service: Deactivated successfully. Apr 30 12:52:51.885638 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 30 12:52:51.896619 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 30 12:52:51.927011 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 30 12:52:51.928907 systemd-networkd[1381]: eth0: Gained IPv6LL Apr 30 12:52:51.938762 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 30 12:52:51.954847 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 30 12:52:51.957535 systemd[1]: Reached target getty.target - Login Prompts. Apr 30 12:52:51.959842 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 30 12:52:51.963947 systemd[1]: Reached target network-online.target - Network is Online. Apr 30 12:52:51.982530 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 12:52:51.986662 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 30 12:52:52.028048 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 30 12:52:52.184907 systemd-networkd[1381]: eth1: Gained IPv6LL Apr 30 12:52:53.209530 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 12:52:53.212772 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 30 12:52:53.218053 systemd[1]: Startup finished in 1.405s (kernel) + 6.261s (initrd) + 7.259s (userspace) = 14.925s. Apr 30 12:52:53.221732 (kubelet)[1566]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 30 12:52:53.625592 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 30 12:52:53.633660 systemd[1]: Started sshd@0-164.92.126.92:22-139.178.68.195:48446.service - OpenSSH per-connection server daemon (139.178.68.195:48446). Apr 30 12:52:53.739009 sshd[1577]: Accepted publickey for core from 139.178.68.195 port 48446 ssh2: RSA SHA256:Hg/II7CXXD9FZCQNXNrtUs1O5U6YBO/6a1JJNL5R96E Apr 30 12:52:53.741703 sshd-session[1577]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:53.755512 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 30 12:52:53.764838 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 30 12:52:53.794605 systemd-logind[1459]: New session 1 of user core. Apr 30 12:52:53.808699 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 30 12:52:53.819649 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 30 12:52:53.826400 (systemd)[1581]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 30 12:52:53.834267 systemd-logind[1459]: New session c1 of user core. Apr 30 12:52:54.090660 kubelet[1566]: E0430 12:52:54.090543 1566 run.go:74] "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" Apr 30 12:52:54.095450 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 30 12:52:54.095686 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 30 12:52:54.097297 systemd[1]: kubelet.service: Consumed 1.386s CPU time, 242.7M memory peak. Apr 30 12:52:54.119429 systemd[1581]: Queued start job for default target default.target. Apr 30 12:52:54.135821 systemd[1581]: Created slice app.slice - User Application Slice. Apr 30 12:52:54.135863 systemd[1581]: Reached target paths.target - Paths. Apr 30 12:52:54.135922 systemd[1581]: Reached target timers.target - Timers. Apr 30 12:52:54.138062 systemd[1581]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 30 12:52:54.155334 systemd[1581]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 30 12:52:54.155527 systemd[1581]: Reached target sockets.target - Sockets. Apr 30 12:52:54.155603 systemd[1581]: Reached target basic.target - Basic System. Apr 30 12:52:54.155666 systemd[1581]: Reached target default.target - Main User Target. Apr 30 12:52:54.155711 systemd[1581]: Startup finished in 308ms. Apr 30 12:52:54.156010 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 30 12:52:54.164682 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 30 12:52:54.248248 systemd[1]: Started sshd@1-164.92.126.92:22-139.178.68.195:48452.service - OpenSSH per-connection server daemon (139.178.68.195:48452). Apr 30 12:52:54.320872 sshd[1595]: Accepted publickey for core from 139.178.68.195 port 48452 ssh2: RSA SHA256:Hg/II7CXXD9FZCQNXNrtUs1O5U6YBO/6a1JJNL5R96E Apr 30 12:52:54.323296 sshd-session[1595]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:54.331675 systemd-logind[1459]: New session 2 of user core. Apr 30 12:52:54.341442 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 30 12:52:54.409632 sshd[1597]: Connection closed by 139.178.68.195 port 48452 Apr 30 12:52:54.409503 sshd-session[1595]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:54.427829 systemd[1]: sshd@1-164.92.126.92:22-139.178.68.195:48452.service: Deactivated successfully. Apr 30 12:52:54.430440 systemd[1]: session-2.scope: Deactivated successfully. Apr 30 12:52:54.433433 systemd-logind[1459]: Session 2 logged out. Waiting for processes to exit. Apr 30 12:52:54.439715 systemd[1]: Started sshd@2-164.92.126.92:22-139.178.68.195:48458.service - OpenSSH per-connection server daemon (139.178.68.195:48458). Apr 30 12:52:54.443903 systemd-logind[1459]: Removed session 2. Apr 30 12:52:54.491723 sshd[1602]: Accepted publickey for core from 139.178.68.195 port 48458 ssh2: RSA SHA256:Hg/II7CXXD9FZCQNXNrtUs1O5U6YBO/6a1JJNL5R96E Apr 30 12:52:54.494165 sshd-session[1602]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:54.503180 systemd-logind[1459]: New session 3 of user core. Apr 30 12:52:54.517506 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 30 12:52:54.577104 sshd[1605]: Connection closed by 139.178.68.195 port 48458 Apr 30 12:52:54.577379 sshd-session[1602]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:54.590348 systemd[1]: sshd@2-164.92.126.92:22-139.178.68.195:48458.service: Deactivated successfully. Apr 30 12:52:54.592896 systemd[1]: session-3.scope: Deactivated successfully. Apr 30 12:52:54.597336 systemd-logind[1459]: Session 3 logged out. Waiting for processes to exit. Apr 30 12:52:54.610684 systemd[1]: Started sshd@3-164.92.126.92:22-139.178.68.195:48472.service - OpenSSH per-connection server daemon (139.178.68.195:48472). Apr 30 12:52:54.614111 systemd-logind[1459]: Removed session 3. Apr 30 12:52:54.666741 sshd[1610]: Accepted publickey for core from 139.178.68.195 port 48472 ssh2: RSA SHA256:Hg/II7CXXD9FZCQNXNrtUs1O5U6YBO/6a1JJNL5R96E Apr 30 12:52:54.669228 sshd-session[1610]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:54.677990 systemd-logind[1459]: New session 4 of user core. Apr 30 12:52:54.689516 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 30 12:52:54.756441 sshd[1613]: Connection closed by 139.178.68.195 port 48472 Apr 30 12:52:54.757325 sshd-session[1610]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:54.775691 systemd[1]: sshd@3-164.92.126.92:22-139.178.68.195:48472.service: Deactivated successfully. Apr 30 12:52:54.778382 systemd[1]: session-4.scope: Deactivated successfully. Apr 30 12:52:54.780887 systemd-logind[1459]: Session 4 logged out. Waiting for processes to exit. Apr 30 12:52:54.786639 systemd[1]: Started sshd@4-164.92.126.92:22-139.178.68.195:48484.service - OpenSSH per-connection server daemon (139.178.68.195:48484). Apr 30 12:52:54.788932 systemd-logind[1459]: Removed session 4. Apr 30 12:52:54.854592 sshd[1618]: Accepted publickey for core from 139.178.68.195 port 48484 ssh2: RSA SHA256:Hg/II7CXXD9FZCQNXNrtUs1O5U6YBO/6a1JJNL5R96E Apr 30 12:52:54.856862 sshd-session[1618]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 30 12:52:54.868564 systemd-logind[1459]: New session 5 of user core. Apr 30 12:52:54.874543 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 30 12:52:54.957492 sudo[1622]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 30 12:52:54.958051 sudo[1622]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 30 12:52:55.935162 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 12:52:55.936140 systemd[1]: kubelet.service: Consumed 1.386s CPU time, 242.7M memory peak. Apr 30 12:52:55.944576 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 12:52:56.001396 systemd[1]: Reload requested from client PID 1660 ('systemctl') (unit session-5.scope)... Apr 30 12:52:56.001421 systemd[1]: Reloading... Apr 30 12:52:56.172126 zram_generator::config[1703]: No configuration found. Apr 30 12:52:56.407299 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. Apr 30 12:52:56.582515 systemd[1]: Reloading finished in 580 ms. Apr 30 12:52:56.666379 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 12:52:56.674287 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 12:52:56.678338 systemd[1]: kubelet.service: Deactivated successfully. Apr 30 12:52:56.678723 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 12:52:56.678795 systemd[1]: kubelet.service: Consumed 142ms CPU time, 83.2M memory peak. Apr 30 12:52:56.681634 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 30 12:52:56.891362 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 30 12:52:56.892667 (kubelet)[1759]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Apr 30 12:52:56.965630 kubelet[1759]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Apr 30 12:52:56.965630 kubelet[1759]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Apr 30 12:52:56.965630 kubelet[1759]: 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. Apr 30 12:52:56.965630 kubelet[1759]: I0430 12:52:56.965417 1759 server.go:205] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Apr 30 12:52:57.185106 kubelet[1759]: I0430 12:52:57.185041 1759 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Apr 30 12:52:57.186123 kubelet[1759]: I0430 12:52:57.185281 1759 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 30 12:52:57.186123 kubelet[1759]: I0430 12:52:57.185592 1759 server.go:927] "Client rotation is on, will bootstrap in background" Apr 30 12:52:57.202600 kubelet[1759]: I0430 12:52:57.202534 1759 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 30 12:52:57.219182 kubelet[1759]: I0430 12:52:57.218892 1759 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Apr 30 12:52:57.222720 kubelet[1759]: I0430 12:52:57.222059 1759 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 30 12:52:57.222720 kubelet[1759]: I0430 12:52:57.222183 1759 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"164.92.126.92","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Apr 30 12:52:57.223599 kubelet[1759]: I0430 12:52:57.223560 1759 topology_manager.go:138] "Creating topology manager with none policy" Apr 30 12:52:57.223759 kubelet[1759]: I0430 12:52:57.223747 1759 container_manager_linux.go:301] "Creating device plugin manager" Apr 30 12:52:57.224131 kubelet[1759]: I0430 12:52:57.224113 1759 state_mem.go:36] "Initialized new in-memory state store" Apr 30 12:52:57.225409 kubelet[1759]: I0430 12:52:57.225384 1759 kubelet.go:400] "Attempting to sync node with API server" Apr 30 12:52:57.225540 kubelet[1759]: I0430 12:52:57.225527 1759 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 30 12:52:57.225649 kubelet[1759]: I0430 12:52:57.225641 1759 kubelet.go:312] "Adding apiserver pod source" Apr 30 12:52:57.225733 kubelet[1759]: I0430 12:52:57.225725 1759 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 30 12:52:57.229537 kubelet[1759]: E0430 12:52:57.228912 1759 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:52:57.229537 kubelet[1759]: E0430 12:52:57.228981 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:52:57.233163 kubelet[1759]: I0430 12:52:57.233098 1759 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Apr 30 12:52:57.235443 kubelet[1759]: I0430 12:52:57.235353 1759 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Apr 30 12:52:57.235584 kubelet[1759]: W0430 12:52:57.235476 1759 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 30 12:52:57.236608 kubelet[1759]: I0430 12:52:57.236568 1759 server.go:1264] "Started kubelet" Apr 30 12:52:57.240834 kubelet[1759]: I0430 12:52:57.240336 1759 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Apr 30 12:52:57.242975 kubelet[1759]: W0430 12:52:57.242847 1759 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "164.92.126.92" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 30 12:52:57.242975 kubelet[1759]: E0430 12:52:57.242947 1759 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "164.92.126.92" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Apr 30 12:52:57.243220 kubelet[1759]: W0430 12:52:57.243126 1759 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 30 12:52:57.243220 kubelet[1759]: E0430 12:52:57.243148 1759 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Apr 30 12:52:57.249804 kubelet[1759]: I0430 12:52:57.249691 1759 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Apr 30 12:52:57.252970 kubelet[1759]: I0430 12:52:57.251866 1759 server.go:455] "Adding debug handlers to kubelet server" Apr 30 12:52:57.253277 kubelet[1759]: I0430 12:52:57.253202 1759 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Apr 30 12:52:57.253662 kubelet[1759]: I0430 12:52:57.253629 1759 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Apr 30 12:52:57.257556 kubelet[1759]: I0430 12:52:57.256917 1759 volume_manager.go:291] "Starting Kubelet Volume Manager" Apr 30 12:52:57.258454 kubelet[1759]: I0430 12:52:57.258416 1759 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Apr 30 12:52:57.258568 kubelet[1759]: I0430 12:52:57.258517 1759 reconciler.go:26] "Reconciler: start to sync state" Apr 30 12:52:57.260347 kubelet[1759]: I0430 12:52:57.260310 1759 factory.go:221] Registration of the systemd container factory successfully Apr 30 12:52:57.260464 kubelet[1759]: I0430 12:52:57.260442 1759 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Apr 30 12:52:57.261774 kubelet[1759]: E0430 12:52:57.261434 1759 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:{164.92.126.92.183b19bc56c53414 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:164.92.126.92,UID:164.92.126.92,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:164.92.126.92,},FirstTimestamp:2025-04-30 12:52:57.236509716 +0000 UTC m=+0.338865305,LastTimestamp:2025-04-30 12:52:57.236509716 +0000 UTC m=+0.338865305,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:164.92.126.92,}" Apr 30 12:52:57.262990 kubelet[1759]: E0430 12:52:57.262881 1759 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 30 12:52:57.266113 kubelet[1759]: I0430 12:52:57.265455 1759 factory.go:221] Registration of the containerd container factory successfully Apr 30 12:52:57.292649 kubelet[1759]: E0430 12:52:57.286106 1759 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"164.92.126.92\" not found" node="164.92.126.92" Apr 30 12:52:57.302123 kubelet[1759]: I0430 12:52:57.301818 1759 cpu_manager.go:214] "Starting CPU manager" policy="none" Apr 30 12:52:57.302123 kubelet[1759]: I0430 12:52:57.301843 1759 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Apr 30 12:52:57.302123 kubelet[1759]: I0430 12:52:57.301867 1759 state_mem.go:36] "Initialized new in-memory state store" Apr 30 12:52:57.307542 kubelet[1759]: I0430 12:52:57.307497 1759 policy_none.go:49] "None policy: Start" Apr 30 12:52:57.309054 kubelet[1759]: I0430 12:52:57.308892 1759 memory_manager.go:170] "Starting memorymanager" policy="None" Apr 30 12:52:57.309054 kubelet[1759]: I0430 12:52:57.308945 1759 state_mem.go:35] "Initializing new in-memory state store" Apr 30 12:52:57.331016 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Apr 30 12:52:57.346317 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Apr 30 12:52:57.353835 kubelet[1759]: I0430 12:52:57.353482 1759 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Apr 30 12:52:57.354957 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Apr 30 12:52:57.355835 kubelet[1759]: I0430 12:52:57.355613 1759 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Apr 30 12:52:57.355835 kubelet[1759]: I0430 12:52:57.355647 1759 status_manager.go:217] "Starting to sync pod status with apiserver" Apr 30 12:52:57.355835 kubelet[1759]: I0430 12:52:57.355678 1759 kubelet.go:2337] "Starting kubelet main sync loop" Apr 30 12:52:57.355835 kubelet[1759]: E0430 12:52:57.355760 1759 kubelet.go:2361] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Apr 30 12:52:57.364741 kubelet[1759]: I0430 12:52:57.364692 1759 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Apr 30 12:52:57.365093 kubelet[1759]: I0430 12:52:57.364916 1759 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Apr 30 12:52:57.365093 kubelet[1759]: I0430 12:52:57.365044 1759 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Apr 30 12:52:57.369133 kubelet[1759]: I0430 12:52:57.367919 1759 kubelet_node_status.go:73] "Attempting to register node" node="164.92.126.92" Apr 30 12:52:57.370629 kubelet[1759]: E0430 12:52:57.370436 1759 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"164.92.126.92\" not found" Apr 30 12:52:57.375124 kubelet[1759]: I0430 12:52:57.374940 1759 kubelet_node_status.go:76] "Successfully registered node" node="164.92.126.92" Apr 30 12:52:57.389105 kubelet[1759]: E0430 12:52:57.387668 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:57.430347 sudo[1622]: pam_unix(sudo:session): session closed for user root Apr 30 12:52:57.434310 sshd[1621]: Connection closed by 139.178.68.195 port 48484 Apr 30 12:52:57.435538 sshd-session[1618]: pam_unix(sshd:session): session closed for user core Apr 30 12:52:57.441209 systemd[1]: sshd@4-164.92.126.92:22-139.178.68.195:48484.service: Deactivated successfully. Apr 30 12:52:57.445603 systemd[1]: session-5.scope: Deactivated successfully. Apr 30 12:52:57.446178 systemd[1]: session-5.scope: Consumed 690ms CPU time, 111.9M memory peak. Apr 30 12:52:57.448201 systemd-logind[1459]: Session 5 logged out. Waiting for processes to exit. Apr 30 12:52:57.449970 systemd-logind[1459]: Removed session 5. Apr 30 12:52:57.488141 kubelet[1759]: E0430 12:52:57.487938 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:57.588241 kubelet[1759]: E0430 12:52:57.588163 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:57.689443 kubelet[1759]: E0430 12:52:57.689353 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:57.790602 kubelet[1759]: E0430 12:52:57.790411 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:57.891659 kubelet[1759]: E0430 12:52:57.891541 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:57.991980 kubelet[1759]: E0430 12:52:57.991865 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:58.092233 kubelet[1759]: E0430 12:52:58.092160 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:58.187836 kubelet[1759]: I0430 12:52:58.187760 1759 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 30 12:52:58.188019 kubelet[1759]: W0430 12:52:58.188006 1759 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Apr 30 12:52:58.188142 kubelet[1759]: W0430 12:52:58.188044 1759 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Apr 30 12:52:58.193082 kubelet[1759]: E0430 12:52:58.192965 1759 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"164.92.126.92\" not found" Apr 30 12:52:58.229783 kubelet[1759]: E0430 12:52:58.229715 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:52:58.294608 kubelet[1759]: I0430 12:52:58.294543 1759 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 30 12:52:58.295297 containerd[1477]: time="2025-04-30T12:52:58.294989211Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 30 12:52:58.295853 kubelet[1759]: I0430 12:52:58.295508 1759 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 30 12:52:59.229879 kubelet[1759]: I0430 12:52:59.229797 1759 apiserver.go:52] "Watching apiserver" Apr 30 12:52:59.230554 kubelet[1759]: E0430 12:52:59.229915 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:52:59.235993 kubelet[1759]: I0430 12:52:59.235932 1759 topology_manager.go:215] "Topology Admit Handler" podUID="b7cc66d5-fa1e-43df-8b3d-62067b7206b9" podNamespace="kube-flannel" podName="kube-flannel-ds-mbzk2" Apr 30 12:52:59.236188 kubelet[1759]: I0430 12:52:59.236047 1759 topology_manager.go:215] "Topology Admit Handler" podUID="31c2982d-e2fe-4c5d-9f9a-562b528ef2e0" podNamespace="kube-system" podName="kube-proxy-55gz6" Apr 30 12:52:59.249683 systemd[1]: Created slice kubepods-besteffort-pod31c2982d_e2fe_4c5d_9f9a_562b528ef2e0.slice - libcontainer container kubepods-besteffort-pod31c2982d_e2fe_4c5d_9f9a_562b528ef2e0.slice. Apr 30 12:52:59.258838 kubelet[1759]: I0430 12:52:59.258780 1759 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Apr 30 12:52:59.264418 systemd[1]: Created slice kubepods-burstable-podb7cc66d5_fa1e_43df_8b3d_62067b7206b9.slice - libcontainer container kubepods-burstable-podb7cc66d5_fa1e_43df_8b3d_62067b7206b9.slice. Apr 30 12:52:59.272312 kubelet[1759]: I0430 12:52:59.272248 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wnmkd\" (UniqueName: \"kubernetes.io/projected/b7cc66d5-fa1e-43df-8b3d-62067b7206b9-kube-api-access-wnmkd\") pod \"kube-flannel-ds-mbzk2\" (UID: \"b7cc66d5-fa1e-43df-8b3d-62067b7206b9\") " pod="kube-flannel/kube-flannel-ds-mbzk2" Apr 30 12:52:59.272312 kubelet[1759]: I0430 12:52:59.272313 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/31c2982d-e2fe-4c5d-9f9a-562b528ef2e0-kube-proxy\") pod \"kube-proxy-55gz6\" (UID: \"31c2982d-e2fe-4c5d-9f9a-562b528ef2e0\") " pod="kube-system/kube-proxy-55gz6" Apr 30 12:52:59.272549 kubelet[1759]: I0430 12:52:59.272341 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/31c2982d-e2fe-4c5d-9f9a-562b528ef2e0-lib-modules\") pod \"kube-proxy-55gz6\" (UID: \"31c2982d-e2fe-4c5d-9f9a-562b528ef2e0\") " pod="kube-system/kube-proxy-55gz6" Apr 30 12:52:59.272549 kubelet[1759]: I0430 12:52:59.272378 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-c9r27\" (UniqueName: \"kubernetes.io/projected/31c2982d-e2fe-4c5d-9f9a-562b528ef2e0-kube-api-access-c9r27\") pod \"kube-proxy-55gz6\" (UID: \"31c2982d-e2fe-4c5d-9f9a-562b528ef2e0\") " pod="kube-system/kube-proxy-55gz6" Apr 30 12:52:59.272549 kubelet[1759]: I0430 12:52:59.272408 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/b7cc66d5-fa1e-43df-8b3d-62067b7206b9-run\") pod \"kube-flannel-ds-mbzk2\" (UID: \"b7cc66d5-fa1e-43df-8b3d-62067b7206b9\") " pod="kube-flannel/kube-flannel-ds-mbzk2" Apr 30 12:52:59.272549 kubelet[1759]: I0430 12:52:59.272431 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/b7cc66d5-fa1e-43df-8b3d-62067b7206b9-cni-plugin\") pod \"kube-flannel-ds-mbzk2\" (UID: \"b7cc66d5-fa1e-43df-8b3d-62067b7206b9\") " pod="kube-flannel/kube-flannel-ds-mbzk2" Apr 30 12:52:59.272549 kubelet[1759]: I0430 12:52:59.272453 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/b7cc66d5-fa1e-43df-8b3d-62067b7206b9-flannel-cfg\") pod \"kube-flannel-ds-mbzk2\" (UID: \"b7cc66d5-fa1e-43df-8b3d-62067b7206b9\") " pod="kube-flannel/kube-flannel-ds-mbzk2" Apr 30 12:52:59.272829 kubelet[1759]: I0430 12:52:59.272480 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/b7cc66d5-fa1e-43df-8b3d-62067b7206b9-cni\") pod \"kube-flannel-ds-mbzk2\" (UID: \"b7cc66d5-fa1e-43df-8b3d-62067b7206b9\") " pod="kube-flannel/kube-flannel-ds-mbzk2" Apr 30 12:52:59.272829 kubelet[1759]: I0430 12:52:59.272553 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b7cc66d5-fa1e-43df-8b3d-62067b7206b9-xtables-lock\") pod \"kube-flannel-ds-mbzk2\" (UID: \"b7cc66d5-fa1e-43df-8b3d-62067b7206b9\") " pod="kube-flannel/kube-flannel-ds-mbzk2" Apr 30 12:52:59.272829 kubelet[1759]: I0430 12:52:59.272584 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/31c2982d-e2fe-4c5d-9f9a-562b528ef2e0-xtables-lock\") pod \"kube-proxy-55gz6\" (UID: \"31c2982d-e2fe-4c5d-9f9a-562b528ef2e0\") " pod="kube-system/kube-proxy-55gz6" Apr 30 12:52:59.559794 kubelet[1759]: E0430 12:52:59.559623 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:52:59.561639 containerd[1477]: time="2025-04-30T12:52:59.561553419Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-55gz6,Uid:31c2982d-e2fe-4c5d-9f9a-562b528ef2e0,Namespace:kube-system,Attempt:0,}" Apr 30 12:52:59.579250 kubelet[1759]: E0430 12:52:59.579200 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:52:59.579819 containerd[1477]: time="2025-04-30T12:52:59.579777582Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-mbzk2,Uid:b7cc66d5-fa1e-43df-8b3d-62067b7206b9,Namespace:kube-flannel,Attempt:0,}" Apr 30 12:53:00.186387 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount215509121.mount: Deactivated successfully. Apr 30 12:53:00.205821 containerd[1477]: time="2025-04-30T12:53:00.205705747Z" 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\"}" Apr 30 12:53:00.210076 containerd[1477]: time="2025-04-30T12:53:00.209938348Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" Apr 30 12:53:00.214100 containerd[1477]: time="2025-04-30T12:53:00.212574615Z" 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\"}" Apr 30 12:53:00.214684 containerd[1477]: time="2025-04-30T12:53:00.214635312Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 30 12:53:00.217549 containerd[1477]: time="2025-04-30T12:53:00.217493736Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Apr 30 12:53:00.222793 containerd[1477]: time="2025-04-30T12:53:00.222738912Z" 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\"}" Apr 30 12:53:00.224762 containerd[1477]: time="2025-04-30T12:53:00.224695661Z" 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 662.944881ms" Apr 30 12:53:00.227656 containerd[1477]: time="2025-04-30T12:53:00.227588672Z" 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 647.624704ms" Apr 30 12:53:00.230426 kubelet[1759]: E0430 12:53:00.230364 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:00.421265 containerd[1477]: time="2025-04-30T12:53:00.419602728Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 12:53:00.427787 containerd[1477]: time="2025-04-30T12:53:00.427360790Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 12:53:00.427787 containerd[1477]: time="2025-04-30T12:53:00.427456535Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 12:53:00.427787 containerd[1477]: time="2025-04-30T12:53:00.427489210Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:00.427787 containerd[1477]: time="2025-04-30T12:53:00.427604297Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:00.428447 containerd[1477]: time="2025-04-30T12:53:00.428196770Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 12:53:00.428447 containerd[1477]: time="2025-04-30T12:53:00.428331694Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:00.429225 containerd[1477]: time="2025-04-30T12:53:00.429087656Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:00.548467 systemd[1]: run-containerd-runc-k8s.io-e98b1405320a888ec1ae645373280f07fc96fec9437ee98ec129e2dce069d980-runc.tQjPpQ.mount: Deactivated successfully. Apr 30 12:53:00.567526 systemd[1]: Started cri-containerd-e98b1405320a888ec1ae645373280f07fc96fec9437ee98ec129e2dce069d980.scope - libcontainer container e98b1405320a888ec1ae645373280f07fc96fec9437ee98ec129e2dce069d980. Apr 30 12:53:00.579359 systemd[1]: Started cri-containerd-38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964.scope - libcontainer container 38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964. Apr 30 12:53:00.633762 containerd[1477]: time="2025-04-30T12:53:00.633535157Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-55gz6,Uid:31c2982d-e2fe-4c5d-9f9a-562b528ef2e0,Namespace:kube-system,Attempt:0,} returns sandbox id \"e98b1405320a888ec1ae645373280f07fc96fec9437ee98ec129e2dce069d980\"" Apr 30 12:53:00.634996 kubelet[1759]: E0430 12:53:00.634936 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:00.638964 containerd[1477]: time="2025-04-30T12:53:00.638897409Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\"" Apr 30 12:53:00.678755 containerd[1477]: time="2025-04-30T12:53:00.678708540Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-mbzk2,Uid:b7cc66d5-fa1e-43df-8b3d-62067b7206b9,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964\"" Apr 30 12:53:00.680370 kubelet[1759]: E0430 12:53:00.680320 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:01.231316 kubelet[1759]: E0430 12:53:01.231263 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:01.940402 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2742566576.mount: Deactivated successfully. Apr 30 12:53:02.233238 kubelet[1759]: E0430 12:53:02.232860 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:02.582386 containerd[1477]: time="2025-04-30T12:53:02.582290074Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.12\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:02.584517 containerd[1477]: time="2025-04-30T12:53:02.584429435Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.12: active requests=0, bytes read=29185817" Apr 30 12:53:02.587157 containerd[1477]: time="2025-04-30T12:53:02.587052011Z" level=info msg="ImageCreate event name:\"sha256:c9356fea5d151501039907c3ba870272461396117eabc74063632616f4e31b2b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:02.591595 containerd[1477]: time="2025-04-30T12:53:02.591490233Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:ea8c7d5392acf6b0c11ebba78301e1a6c2dc6abcd7544102ed578e49d1c82f15\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:02.593466 containerd[1477]: time="2025-04-30T12:53:02.592835061Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.12\" with image id \"sha256:c9356fea5d151501039907c3ba870272461396117eabc74063632616f4e31b2b\", repo tag \"registry.k8s.io/kube-proxy:v1.30.12\", repo digest \"registry.k8s.io/kube-proxy@sha256:ea8c7d5392acf6b0c11ebba78301e1a6c2dc6abcd7544102ed578e49d1c82f15\", size \"29184836\" in 1.95388369s" Apr 30 12:53:02.593466 containerd[1477]: time="2025-04-30T12:53:02.592899416Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.12\" returns image reference \"sha256:c9356fea5d151501039907c3ba870272461396117eabc74063632616f4e31b2b\"" Apr 30 12:53:02.595240 containerd[1477]: time="2025-04-30T12:53:02.595189423Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Apr 30 12:53:02.597296 containerd[1477]: time="2025-04-30T12:53:02.597089422Z" level=info msg="CreateContainer within sandbox \"e98b1405320a888ec1ae645373280f07fc96fec9437ee98ec129e2dce069d980\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 30 12:53:02.626921 containerd[1477]: time="2025-04-30T12:53:02.626667049Z" level=info msg="CreateContainer within sandbox \"e98b1405320a888ec1ae645373280f07fc96fec9437ee98ec129e2dce069d980\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"565dd7183eac3e947168ada1201a44edba832e258966296bac34f1ee60ffe0e3\"" Apr 30 12:53:02.628247 containerd[1477]: time="2025-04-30T12:53:02.628136688Z" level=info msg="StartContainer for \"565dd7183eac3e947168ada1201a44edba832e258966296bac34f1ee60ffe0e3\"" Apr 30 12:53:02.685562 systemd[1]: Started cri-containerd-565dd7183eac3e947168ada1201a44edba832e258966296bac34f1ee60ffe0e3.scope - libcontainer container 565dd7183eac3e947168ada1201a44edba832e258966296bac34f1ee60ffe0e3. Apr 30 12:53:02.741088 containerd[1477]: time="2025-04-30T12:53:02.740713040Z" level=info msg="StartContainer for \"565dd7183eac3e947168ada1201a44edba832e258966296bac34f1ee60ffe0e3\" returns successfully" Apr 30 12:53:03.234114 kubelet[1759]: E0430 12:53:03.234038 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:03.394780 kubelet[1759]: E0430 12:53:03.394727 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:03.410295 kubelet[1759]: I0430 12:53:03.410210 1759 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-55gz6" podStartSLOduration=4.453709677 podStartE2EDuration="6.410183959s" podCreationTimestamp="2025-04-30 12:52:57 +0000 UTC" firstStartedPulling="2025-04-30 12:53:00.637755518 +0000 UTC m=+3.740111106" lastFinishedPulling="2025-04-30 12:53:02.594229796 +0000 UTC m=+5.696585388" observedRunningTime="2025-04-30 12:53:03.40981431 +0000 UTC m=+6.512169893" watchObservedRunningTime="2025-04-30 12:53:03.410183959 +0000 UTC m=+6.512539560" Apr 30 12:53:04.234579 kubelet[1759]: E0430 12:53:04.234470 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:04.397767 kubelet[1759]: E0430 12:53:04.397689 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:04.724022 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount934274300.mount: Deactivated successfully. Apr 30 12:53:04.784229 containerd[1477]: time="2025-04-30T12:53:04.784158002Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:04.786174 containerd[1477]: time="2025-04-30T12:53:04.786093157Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Apr 30 12:53:04.787955 containerd[1477]: time="2025-04-30T12:53:04.787869975Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:04.792867 containerd[1477]: time="2025-04-30T12:53:04.792735753Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:04.795137 containerd[1477]: time="2025-04-30T12:53:04.794544390Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 2.199297369s" Apr 30 12:53:04.795137 containerd[1477]: time="2025-04-30T12:53:04.794595969Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Apr 30 12:53:04.798777 containerd[1477]: time="2025-04-30T12:53:04.798539249Z" level=info msg="CreateContainer within sandbox \"38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 30 12:53:04.822794 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1503191931.mount: Deactivated successfully. Apr 30 12:53:04.825147 containerd[1477]: time="2025-04-30T12:53:04.824780084Z" level=info msg="CreateContainer within sandbox \"38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc\"" Apr 30 12:53:04.827198 containerd[1477]: time="2025-04-30T12:53:04.825690901Z" level=info msg="StartContainer for \"9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc\"" Apr 30 12:53:04.874499 systemd[1]: Started cri-containerd-9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc.scope - libcontainer container 9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc. Apr 30 12:53:04.916173 systemd[1]: cri-containerd-9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc.scope: Deactivated successfully. Apr 30 12:53:04.919312 containerd[1477]: time="2025-04-30T12:53:04.919251805Z" level=info msg="StartContainer for \"9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc\" returns successfully" Apr 30 12:53:04.997805 containerd[1477]: time="2025-04-30T12:53:04.997495646Z" level=info msg="shim disconnected" id=9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc namespace=k8s.io Apr 30 12:53:04.997805 containerd[1477]: time="2025-04-30T12:53:04.997572096Z" level=warning msg="cleaning up after shim disconnected" id=9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc namespace=k8s.io Apr 30 12:53:04.997805 containerd[1477]: time="2025-04-30T12:53:04.997584672Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 30 12:53:05.234951 kubelet[1759]: E0430 12:53:05.234867 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:05.401483 kubelet[1759]: E0430 12:53:05.401443 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:05.403041 containerd[1477]: time="2025-04-30T12:53:05.402691820Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Apr 30 12:53:05.595139 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9ca425bb48b11d9440dd60a88303af285ee26953cb330d747a679d595f79a5bc-rootfs.mount: Deactivated successfully. Apr 30 12:53:05.752577 systemd-resolved[1337]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.2. Apr 30 12:53:06.235193 kubelet[1759]: E0430 12:53:06.235126 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:07.235702 kubelet[1759]: E0430 12:53:07.235602 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:07.551937 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1594937386.mount: Deactivated successfully. Apr 30 12:53:08.236244 kubelet[1759]: E0430 12:53:08.236098 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:08.453468 containerd[1477]: time="2025-04-30T12:53:08.453148718Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:08.455977 containerd[1477]: time="2025-04-30T12:53:08.455866351Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Apr 30 12:53:08.458406 containerd[1477]: time="2025-04-30T12:53:08.458310004Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:08.464359 containerd[1477]: time="2025-04-30T12:53:08.464240022Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:08.467912 containerd[1477]: time="2025-04-30T12:53:08.466279171Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.063526134s" Apr 30 12:53:08.467912 containerd[1477]: time="2025-04-30T12:53:08.466336375Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Apr 30 12:53:08.469825 containerd[1477]: time="2025-04-30T12:53:08.469752595Z" level=info msg="CreateContainer within sandbox \"38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 30 12:53:08.487911 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1574926561.mount: Deactivated successfully. Apr 30 12:53:08.492899 containerd[1477]: time="2025-04-30T12:53:08.492699448Z" level=info msg="CreateContainer within sandbox \"38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885\"" Apr 30 12:53:08.495111 containerd[1477]: time="2025-04-30T12:53:08.494040213Z" level=info msg="StartContainer for \"9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885\"" Apr 30 12:53:08.543484 systemd[1]: Started cri-containerd-9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885.scope - libcontainer container 9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885. Apr 30 12:53:08.583809 systemd[1]: cri-containerd-9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885.scope: Deactivated successfully. Apr 30 12:53:08.589334 containerd[1477]: time="2025-04-30T12:53:08.589207129Z" level=warning msg="error from *cgroupsv2.Manager.EventChan" error="failed to add inotify watch for \"/sys/fs/cgroup/kubepods.slice/kubepods-burstable.slice/kubepods-burstable-podb7cc66d5_fa1e_43df_8b3d_62067b7206b9.slice/cri-containerd-9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885.scope/memory.events\": no such file or directory" Apr 30 12:53:08.590005 containerd[1477]: time="2025-04-30T12:53:08.589958104Z" level=info msg="StartContainer for \"9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885\" returns successfully" Apr 30 12:53:08.604368 kubelet[1759]: I0430 12:53:08.604113 1759 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Apr 30 12:53:08.632770 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885-rootfs.mount: Deactivated successfully. Apr 30 12:53:08.697626 containerd[1477]: time="2025-04-30T12:53:08.697461443Z" level=info msg="shim disconnected" id=9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885 namespace=k8s.io Apr 30 12:53:08.697626 containerd[1477]: time="2025-04-30T12:53:08.697540394Z" level=warning msg="cleaning up after shim disconnected" id=9d68a14badb66bb1c7ea20d84bc50acede0cf42bcf7e11621e655a9098924885 namespace=k8s.io Apr 30 12:53:08.697626 containerd[1477]: time="2025-04-30T12:53:08.697552197Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 30 12:53:08.824409 systemd-resolved[1337]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 67.207.67.3. Apr 30 12:53:09.236787 kubelet[1759]: E0430 12:53:09.236551 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:09.414639 kubelet[1759]: E0430 12:53:09.413750 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:09.416744 containerd[1477]: time="2025-04-30T12:53:09.416697667Z" level=info msg="CreateContainer within sandbox \"38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 30 12:53:09.440400 containerd[1477]: time="2025-04-30T12:53:09.440332842Z" level=info msg="CreateContainer within sandbox \"38d94e72bb406e8091f922a73dae99e7dd63fbb16adc0bddd09e87c33e23c964\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"e8c7e51ee3ba4304b057d9b0e971aeaf623fdbd445183085e8cc7d2f9b699773\"" Apr 30 12:53:09.441553 containerd[1477]: time="2025-04-30T12:53:09.441513402Z" level=info msg="StartContainer for \"e8c7e51ee3ba4304b057d9b0e971aeaf623fdbd445183085e8cc7d2f9b699773\"" Apr 30 12:53:09.480391 systemd[1]: Started cri-containerd-e8c7e51ee3ba4304b057d9b0e971aeaf623fdbd445183085e8cc7d2f9b699773.scope - libcontainer container e8c7e51ee3ba4304b057d9b0e971aeaf623fdbd445183085e8cc7d2f9b699773. Apr 30 12:53:09.524897 containerd[1477]: time="2025-04-30T12:53:09.524300063Z" level=info msg="StartContainer for \"e8c7e51ee3ba4304b057d9b0e971aeaf623fdbd445183085e8cc7d2f9b699773\" returns successfully" Apr 30 12:53:10.237575 kubelet[1759]: E0430 12:53:10.237503 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:10.419561 kubelet[1759]: E0430 12:53:10.419308 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:10.598005 systemd-networkd[1381]: flannel.1: Link UP Apr 30 12:53:10.598017 systemd-networkd[1381]: flannel.1: Gained carrier Apr 30 12:53:11.238559 kubelet[1759]: E0430 12:53:11.238471 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:11.421628 kubelet[1759]: E0430 12:53:11.421570 1759 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 67.207.67.2 67.207.67.3 67.207.67.2" Apr 30 12:53:11.896642 systemd-networkd[1381]: flannel.1: Gained IPv6LL Apr 30 12:53:12.239716 kubelet[1759]: E0430 12:53:12.239537 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:13.240706 kubelet[1759]: E0430 12:53:13.240644 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:13.971465 kubelet[1759]: I0430 12:53:13.971330 1759 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-mbzk2" podStartSLOduration=9.184365808 podStartE2EDuration="16.971300289s" podCreationTimestamp="2025-04-30 12:52:57 +0000 UTC" firstStartedPulling="2025-04-30 12:53:00.681054167 +0000 UTC m=+3.783409737" lastFinishedPulling="2025-04-30 12:53:08.467988649 +0000 UTC m=+11.570344218" observedRunningTime="2025-04-30 12:53:10.433774899 +0000 UTC m=+13.536130487" watchObservedRunningTime="2025-04-30 12:53:13.971300289 +0000 UTC m=+17.073655872" Apr 30 12:53:13.971850 kubelet[1759]: I0430 12:53:13.971646 1759 topology_manager.go:215] "Topology Admit Handler" podUID="d245f45a-2f45-410f-aaa0-50ecc37435d5" podNamespace="default" podName="nginx-deployment-85f456d6dd-rhwqj" Apr 30 12:53:13.980812 systemd[1]: Created slice kubepods-besteffort-podd245f45a_2f45_410f_aaa0_50ecc37435d5.slice - libcontainer container kubepods-besteffort-podd245f45a_2f45_410f_aaa0_50ecc37435d5.slice. Apr 30 12:53:14.074312 kubelet[1759]: I0430 12:53:14.074222 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tvzdd\" (UniqueName: \"kubernetes.io/projected/d245f45a-2f45-410f-aaa0-50ecc37435d5-kube-api-access-tvzdd\") pod \"nginx-deployment-85f456d6dd-rhwqj\" (UID: \"d245f45a-2f45-410f-aaa0-50ecc37435d5\") " pod="default/nginx-deployment-85f456d6dd-rhwqj" Apr 30 12:53:14.241694 kubelet[1759]: E0430 12:53:14.241479 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:14.285207 containerd[1477]: time="2025-04-30T12:53:14.285116188Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-rhwqj,Uid:d245f45a-2f45-410f-aaa0-50ecc37435d5,Namespace:default,Attempt:0,}" Apr 30 12:53:14.322791 systemd-networkd[1381]: cni0: Link UP Apr 30 12:53:14.322800 systemd-networkd[1381]: cni0: Gained carrier Apr 30 12:53:14.332754 systemd-networkd[1381]: veth0e524fca: Link UP Apr 30 12:53:14.336847 kernel: cni0: port 1(veth0e524fca) entered blocking state Apr 30 12:53:14.336980 kernel: cni0: port 1(veth0e524fca) entered disabled state Apr 30 12:53:14.335288 systemd-networkd[1381]: cni0: Lost carrier Apr 30 12:53:14.340351 kernel: veth0e524fca: entered allmulticast mode Apr 30 12:53:14.340486 kernel: veth0e524fca: entered promiscuous mode Apr 30 12:53:14.348649 kernel: cni0: port 1(veth0e524fca) entered blocking state Apr 30 12:53:14.348883 kernel: cni0: port 1(veth0e524fca) entered forwarding state Apr 30 12:53:14.348772 systemd-networkd[1381]: veth0e524fca: Gained carrier Apr 30 12:53:14.351245 systemd-networkd[1381]: cni0: Gained carrier Apr 30 12:53:14.360667 containerd[1477]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000a48e8), "name":"cbr0", "type":"bridge"} Apr 30 12:53:14.360667 containerd[1477]: delegateAdd: netconf sent to delegate plugin: Apr 30 12:53:14.393311 containerd[1477]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-04-30T12:53:14.393179470Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 12:53:14.393648 containerd[1477]: time="2025-04-30T12:53:14.393254896Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 12:53:14.393648 containerd[1477]: time="2025-04-30T12:53:14.393278368Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:14.394920 containerd[1477]: time="2025-04-30T12:53:14.394781791Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:14.434472 systemd[1]: Started cri-containerd-0bd6bd229c19b59f098c7fa826794953ad8a2f73443cb95e5c6c71f571f9a0ab.scope - libcontainer container 0bd6bd229c19b59f098c7fa826794953ad8a2f73443cb95e5c6c71f571f9a0ab. Apr 30 12:53:14.500233 containerd[1477]: time="2025-04-30T12:53:14.499965810Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-rhwqj,Uid:d245f45a-2f45-410f-aaa0-50ecc37435d5,Namespace:default,Attempt:0,} returns sandbox id \"0bd6bd229c19b59f098c7fa826794953ad8a2f73443cb95e5c6c71f571f9a0ab\"" Apr 30 12:53:14.506672 containerd[1477]: time="2025-04-30T12:53:14.506245390Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 30 12:53:15.242509 kubelet[1759]: E0430 12:53:15.242341 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:15.864431 systemd-networkd[1381]: veth0e524fca: Gained IPv6LL Apr 30 12:53:15.928930 systemd-networkd[1381]: cni0: Gained IPv6LL Apr 30 12:53:16.243304 kubelet[1759]: E0430 12:53:16.243076 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:17.225965 kubelet[1759]: E0430 12:53:17.225789 1759 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:17.245143 kubelet[1759]: E0430 12:53:17.243362 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:17.592457 systemd-resolved[1337]: Using degraded feature set TCP instead of UDP for DNS server 67.207.67.2. Apr 30 12:53:18.244355 kubelet[1759]: E0430 12:53:18.244301 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:19.050141 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount927432863.mount: Deactivated successfully. Apr 30 12:53:19.245714 kubelet[1759]: E0430 12:53:19.245666 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:20.246494 kubelet[1759]: E0430 12:53:20.246384 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:20.622639 containerd[1477]: time="2025-04-30T12:53:20.622530777Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:20.624855 containerd[1477]: time="2025-04-30T12:53:20.624763840Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73306276" Apr 30 12:53:20.626342 containerd[1477]: time="2025-04-30T12:53:20.626243860Z" level=info msg="ImageCreate event name:\"sha256:244abd08b283a396de679587fab5dec3f2b427a1cc0ada5b813839fcb187f9b8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:20.631114 containerd[1477]: time="2025-04-30T12:53:20.631047705Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:20.633540 containerd[1477]: time="2025-04-30T12:53:20.632950821Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:244abd08b283a396de679587fab5dec3f2b427a1cc0ada5b813839fcb187f9b8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\", size \"73306154\" in 6.12665393s" Apr 30 12:53:20.633540 containerd[1477]: time="2025-04-30T12:53:20.633005894Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:244abd08b283a396de679587fab5dec3f2b427a1cc0ada5b813839fcb187f9b8\"" Apr 30 12:53:20.637032 containerd[1477]: time="2025-04-30T12:53:20.636758239Z" level=info msg="CreateContainer within sandbox \"0bd6bd229c19b59f098c7fa826794953ad8a2f73443cb95e5c6c71f571f9a0ab\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 30 12:53:20.658964 containerd[1477]: time="2025-04-30T12:53:20.658782830Z" level=info msg="CreateContainer within sandbox \"0bd6bd229c19b59f098c7fa826794953ad8a2f73443cb95e5c6c71f571f9a0ab\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"597a92745b9a0661177c37453d6abf8bf2505bd23ecb6fd909f2dbc4dffaf6f9\"" Apr 30 12:53:20.660681 containerd[1477]: time="2025-04-30T12:53:20.659508718Z" level=info msg="StartContainer for \"597a92745b9a0661177c37453d6abf8bf2505bd23ecb6fd909f2dbc4dffaf6f9\"" Apr 30 12:53:20.706366 systemd[1]: run-containerd-runc-k8s.io-597a92745b9a0661177c37453d6abf8bf2505bd23ecb6fd909f2dbc4dffaf6f9-runc.HoW4eL.mount: Deactivated successfully. Apr 30 12:53:20.720365 systemd[1]: Started cri-containerd-597a92745b9a0661177c37453d6abf8bf2505bd23ecb6fd909f2dbc4dffaf6f9.scope - libcontainer container 597a92745b9a0661177c37453d6abf8bf2505bd23ecb6fd909f2dbc4dffaf6f9. Apr 30 12:53:20.767664 containerd[1477]: time="2025-04-30T12:53:20.767599384Z" level=info msg="StartContainer for \"597a92745b9a0661177c37453d6abf8bf2505bd23ecb6fd909f2dbc4dffaf6f9\" returns successfully" Apr 30 12:53:21.247714 kubelet[1759]: E0430 12:53:21.247628 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:21.464213 kubelet[1759]: I0430 12:53:21.464079 1759 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-rhwqj" podStartSLOduration=2.334898645 podStartE2EDuration="8.464032419s" podCreationTimestamp="2025-04-30 12:53:13 +0000 UTC" firstStartedPulling="2025-04-30 12:53:14.505595571 +0000 UTC m=+17.607951144" lastFinishedPulling="2025-04-30 12:53:20.634729338 +0000 UTC m=+23.737084918" observedRunningTime="2025-04-30 12:53:21.4639635 +0000 UTC m=+24.566319079" watchObservedRunningTime="2025-04-30 12:53:21.464032419 +0000 UTC m=+24.566388007" Apr 30 12:53:22.248718 kubelet[1759]: E0430 12:53:22.248625 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:23.248982 kubelet[1759]: E0430 12:53:23.248830 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:24.250104 kubelet[1759]: E0430 12:53:24.250021 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:25.250804 kubelet[1759]: E0430 12:53:25.250701 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:26.251864 kubelet[1759]: E0430 12:53:26.251718 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:27.252377 kubelet[1759]: E0430 12:53:27.252303 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:27.273736 kubelet[1759]: I0430 12:53:27.273679 1759 topology_manager.go:215] "Topology Admit Handler" podUID="cf0fac40-b8cf-49a7-86b5-5dd6c3b79f72" podNamespace="default" podName="nfs-server-provisioner-0" Apr 30 12:53:27.284845 systemd[1]: Created slice kubepods-besteffort-podcf0fac40_b8cf_49a7_86b5_5dd6c3b79f72.slice - libcontainer container kubepods-besteffort-podcf0fac40_b8cf_49a7_86b5_5dd6c3b79f72.slice. Apr 30 12:53:27.371985 kubelet[1759]: I0430 12:53:27.371913 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5sx5l\" (UniqueName: \"kubernetes.io/projected/cf0fac40-b8cf-49a7-86b5-5dd6c3b79f72-kube-api-access-5sx5l\") pod \"nfs-server-provisioner-0\" (UID: \"cf0fac40-b8cf-49a7-86b5-5dd6c3b79f72\") " pod="default/nfs-server-provisioner-0" Apr 30 12:53:27.371985 kubelet[1759]: I0430 12:53:27.371991 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/cf0fac40-b8cf-49a7-86b5-5dd6c3b79f72-data\") pod \"nfs-server-provisioner-0\" (UID: \"cf0fac40-b8cf-49a7-86b5-5dd6c3b79f72\") " pod="default/nfs-server-provisioner-0" Apr 30 12:53:27.589482 containerd[1477]: time="2025-04-30T12:53:27.589410614Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:cf0fac40-b8cf-49a7-86b5-5dd6c3b79f72,Namespace:default,Attempt:0,}" Apr 30 12:53:27.629790 kernel: cni0: port 2(veth1945508b) entered blocking state Apr 30 12:53:27.629910 kernel: cni0: port 2(veth1945508b) entered disabled state Apr 30 12:53:27.629546 systemd-networkd[1381]: veth1945508b: Link UP Apr 30 12:53:27.632510 kernel: veth1945508b: entered allmulticast mode Apr 30 12:53:27.634789 kernel: veth1945508b: entered promiscuous mode Apr 30 12:53:27.648659 kernel: cni0: port 2(veth1945508b) entered blocking state Apr 30 12:53:27.648827 kernel: cni0: port 2(veth1945508b) entered forwarding state Apr 30 12:53:27.649531 systemd-networkd[1381]: veth1945508b: Gained carrier Apr 30 12:53:27.652576 containerd[1477]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000a48e8), "name":"cbr0", "type":"bridge"} Apr 30 12:53:27.652576 containerd[1477]: delegateAdd: netconf sent to delegate plugin: Apr 30 12:53:27.684718 containerd[1477]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-04-30T12:53:27.684285984Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 12:53:27.684718 containerd[1477]: time="2025-04-30T12:53:27.684459965Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 12:53:27.685772 containerd[1477]: time="2025-04-30T12:53:27.684508016Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:27.685772 containerd[1477]: time="2025-04-30T12:53:27.685641431Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:27.724539 systemd[1]: Started cri-containerd-1b55bc24c90d1b7dcb4c4d65341b3977db7d3ad4886b89665da79381c343e224.scope - libcontainer container 1b55bc24c90d1b7dcb4c4d65341b3977db7d3ad4886b89665da79381c343e224. Apr 30 12:53:27.792885 containerd[1477]: time="2025-04-30T12:53:27.792821241Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:cf0fac40-b8cf-49a7-86b5-5dd6c3b79f72,Namespace:default,Attempt:0,} returns sandbox id \"1b55bc24c90d1b7dcb4c4d65341b3977db7d3ad4886b89665da79381c343e224\"" Apr 30 12:53:27.796046 containerd[1477]: time="2025-04-30T12:53:27.795652754Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 30 12:53:28.252587 kubelet[1759]: E0430 12:53:28.252515 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:29.253527 kubelet[1759]: E0430 12:53:29.253435 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:29.432867 systemd-networkd[1381]: veth1945508b: Gained IPv6LL Apr 30 12:53:30.191814 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount690184170.mount: Deactivated successfully. Apr 30 12:53:30.254014 kubelet[1759]: E0430 12:53:30.253949 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:31.254748 kubelet[1759]: E0430 12:53:31.254678 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:32.255770 kubelet[1759]: E0430 12:53:32.255575 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:32.864184 containerd[1477]: time="2025-04-30T12:53:32.864095967Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:32.867234 containerd[1477]: time="2025-04-30T12:53:32.867000226Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Apr 30 12:53:32.869610 containerd[1477]: time="2025-04-30T12:53:32.869506598Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:32.875681 containerd[1477]: time="2025-04-30T12:53:32.875611913Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:32.878901 containerd[1477]: time="2025-04-30T12:53:32.878297092Z" 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 5.082593325s" Apr 30 12:53:32.878901 containerd[1477]: time="2025-04-30T12:53:32.878364116Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Apr 30 12:53:32.885678 containerd[1477]: time="2025-04-30T12:53:32.885416809Z" level=info msg="CreateContainer within sandbox \"1b55bc24c90d1b7dcb4c4d65341b3977db7d3ad4886b89665da79381c343e224\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 30 12:53:32.911128 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3736815414.mount: Deactivated successfully. Apr 30 12:53:32.916953 containerd[1477]: time="2025-04-30T12:53:32.916885743Z" level=info msg="CreateContainer within sandbox \"1b55bc24c90d1b7dcb4c4d65341b3977db7d3ad4886b89665da79381c343e224\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"173ed601b340a65409d145340e8ba2aa8ebfcabd0c1ae394bcfde8e369c894f1\"" Apr 30 12:53:32.918184 containerd[1477]: time="2025-04-30T12:53:32.918122563Z" level=info msg="StartContainer for \"173ed601b340a65409d145340e8ba2aa8ebfcabd0c1ae394bcfde8e369c894f1\"" Apr 30 12:53:32.976417 systemd[1]: Started cri-containerd-173ed601b340a65409d145340e8ba2aa8ebfcabd0c1ae394bcfde8e369c894f1.scope - libcontainer container 173ed601b340a65409d145340e8ba2aa8ebfcabd0c1ae394bcfde8e369c894f1. Apr 30 12:53:33.026775 containerd[1477]: time="2025-04-30T12:53:33.026698672Z" level=info msg="StartContainer for \"173ed601b340a65409d145340e8ba2aa8ebfcabd0c1ae394bcfde8e369c894f1\" returns successfully" Apr 30 12:53:33.256519 kubelet[1759]: E0430 12:53:33.256291 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:33.520913 kubelet[1759]: I0430 12:53:33.520609 1759 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.4351686080000001 podStartE2EDuration="6.520588113s" podCreationTimestamp="2025-04-30 12:53:27 +0000 UTC" firstStartedPulling="2025-04-30 12:53:27.794937773 +0000 UTC m=+30.897368074" lastFinishedPulling="2025-04-30 12:53:32.880431999 +0000 UTC m=+35.982787579" observedRunningTime="2025-04-30 12:53:33.519569161 +0000 UTC m=+36.621924750" watchObservedRunningTime="2025-04-30 12:53:33.520588113 +0000 UTC m=+36.622943702" Apr 30 12:53:34.257443 kubelet[1759]: E0430 12:53:34.257349 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:35.258328 kubelet[1759]: E0430 12:53:35.258233 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:36.258476 kubelet[1759]: E0430 12:53:36.258386 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:36.296015 update_engine[1460]: I20250430 12:53:36.295868 1460 update_attempter.cc:509] Updating boot flags... Apr 30 12:53:36.342639 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2772) Apr 30 12:53:36.467177 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2770) Apr 30 12:53:36.610256 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (2770) Apr 30 12:53:37.226564 kubelet[1759]: E0430 12:53:37.226494 1759 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:37.259636 kubelet[1759]: E0430 12:53:37.259566 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:38.260730 kubelet[1759]: E0430 12:53:38.260665 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:39.261874 kubelet[1759]: E0430 12:53:39.261799 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:40.262921 kubelet[1759]: E0430 12:53:40.262852 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:41.263423 kubelet[1759]: E0430 12:53:41.263346 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:42.264009 kubelet[1759]: E0430 12:53:42.263939 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:42.820395 kubelet[1759]: I0430 12:53:42.820298 1759 topology_manager.go:215] "Topology Admit Handler" podUID="1fec64fd-9557-42d4-9b02-62a2569bef1b" podNamespace="default" podName="test-pod-1" Apr 30 12:53:42.829020 systemd[1]: Created slice kubepods-besteffort-pod1fec64fd_9557_42d4_9b02_62a2569bef1b.slice - libcontainer container kubepods-besteffort-pod1fec64fd_9557_42d4_9b02_62a2569bef1b.slice. Apr 30 12:53:42.880665 kubelet[1759]: I0430 12:53:42.880543 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-857e0bdb-d339-4f9b-9735-98c7f88d5f48\" (UniqueName: \"kubernetes.io/nfs/1fec64fd-9557-42d4-9b02-62a2569bef1b-pvc-857e0bdb-d339-4f9b-9735-98c7f88d5f48\") pod \"test-pod-1\" (UID: \"1fec64fd-9557-42d4-9b02-62a2569bef1b\") " pod="default/test-pod-1" Apr 30 12:53:42.880665 kubelet[1759]: I0430 12:53:42.880603 1759 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-z5sss\" (UniqueName: \"kubernetes.io/projected/1fec64fd-9557-42d4-9b02-62a2569bef1b-kube-api-access-z5sss\") pod \"test-pod-1\" (UID: \"1fec64fd-9557-42d4-9b02-62a2569bef1b\") " pod="default/test-pod-1" Apr 30 12:53:43.020220 kernel: FS-Cache: Loaded Apr 30 12:53:43.098215 kernel: RPC: Registered named UNIX socket transport module. Apr 30 12:53:43.098385 kernel: RPC: Registered udp transport module. Apr 30 12:53:43.099563 kernel: RPC: Registered tcp transport module. Apr 30 12:53:43.099667 kernel: RPC: Registered tcp-with-tls transport module. Apr 30 12:53:43.100319 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 30 12:53:43.265092 kubelet[1759]: E0430 12:53:43.265000 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:43.387271 kernel: NFS: Registering the id_resolver key type Apr 30 12:53:43.387471 kernel: Key type id_resolver registered Apr 30 12:53:43.391418 kernel: Key type id_legacy registered Apr 30 12:53:43.429047 nfsidmap[2818]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '1.1-b-56129568f5' Apr 30 12:53:43.436629 nfsidmap[2819]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain '1.1-b-56129568f5' Apr 30 12:53:43.734079 containerd[1477]: time="2025-04-30T12:53:43.733916151Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1fec64fd-9557-42d4-9b02-62a2569bef1b,Namespace:default,Attempt:0,}" Apr 30 12:53:43.782272 systemd-networkd[1381]: vethb7673f27: Link UP Apr 30 12:53:43.784212 kernel: cni0: port 3(vethb7673f27) entered blocking state Apr 30 12:53:43.784348 kernel: cni0: port 3(vethb7673f27) entered disabled state Apr 30 12:53:43.792209 kernel: vethb7673f27: entered allmulticast mode Apr 30 12:53:43.795101 kernel: vethb7673f27: entered promiscuous mode Apr 30 12:53:43.812009 kernel: cni0: port 3(vethb7673f27) entered blocking state Apr 30 12:53:43.812205 kernel: cni0: port 3(vethb7673f27) entered forwarding state Apr 30 12:53:43.812626 systemd-networkd[1381]: vethb7673f27: Gained carrier Apr 30 12:53:43.815942 containerd[1477]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009a8e8), "name":"cbr0", "type":"bridge"} Apr 30 12:53:43.815942 containerd[1477]: delegateAdd: netconf sent to delegate plugin: Apr 30 12:53:43.878224 containerd[1477]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-04-30T12:53:43.874686402Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 30 12:53:43.878224 containerd[1477]: time="2025-04-30T12:53:43.874855132Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 30 12:53:43.878224 containerd[1477]: time="2025-04-30T12:53:43.874883236Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:43.878224 containerd[1477]: time="2025-04-30T12:53:43.875026667Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 30 12:53:43.936444 systemd[1]: Started cri-containerd-fe48a4e59a2b1762bf9d67f27f553fd9309f446dade064482adb745d027ea5f8.scope - libcontainer container fe48a4e59a2b1762bf9d67f27f553fd9309f446dade064482adb745d027ea5f8. Apr 30 12:53:44.007191 containerd[1477]: time="2025-04-30T12:53:44.006996867Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1fec64fd-9557-42d4-9b02-62a2569bef1b,Namespace:default,Attempt:0,} returns sandbox id \"fe48a4e59a2b1762bf9d67f27f553fd9309f446dade064482adb745d027ea5f8\"" Apr 30 12:53:44.013602 containerd[1477]: time="2025-04-30T12:53:44.012964379Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 30 12:53:44.266250 kubelet[1759]: E0430 12:53:44.266024 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:44.821022 containerd[1477]: time="2025-04-30T12:53:44.820855589Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 30 12:53:44.823028 containerd[1477]: time="2025-04-30T12:53:44.822796185Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Apr 30 12:53:44.829649 containerd[1477]: time="2025-04-30T12:53:44.829408707Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:244abd08b283a396de679587fab5dec3f2b427a1cc0ada5b813839fcb187f9b8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:727fa1dd2cee1ccca9e775e517739b20d5d47bd36b6b5bde8aa708de1348532b\", size \"73306154\" in 816.277624ms" Apr 30 12:53:44.829649 containerd[1477]: time="2025-04-30T12:53:44.829521372Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:244abd08b283a396de679587fab5dec3f2b427a1cc0ada5b813839fcb187f9b8\"" Apr 30 12:53:44.833907 containerd[1477]: time="2025-04-30T12:53:44.833459521Z" level=info msg="CreateContainer within sandbox \"fe48a4e59a2b1762bf9d67f27f553fd9309f446dade064482adb745d027ea5f8\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 30 12:53:44.871416 containerd[1477]: time="2025-04-30T12:53:44.871285822Z" level=info msg="CreateContainer within sandbox \"fe48a4e59a2b1762bf9d67f27f553fd9309f446dade064482adb745d027ea5f8\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"0fd214fcb47bfb160927177429a5e5238c806b1096b76aa6cde6476e71cc5d5a\"" Apr 30 12:53:44.872584 containerd[1477]: time="2025-04-30T12:53:44.872492559Z" level=info msg="StartContainer for \"0fd214fcb47bfb160927177429a5e5238c806b1096b76aa6cde6476e71cc5d5a\"" Apr 30 12:53:44.924509 systemd[1]: Started cri-containerd-0fd214fcb47bfb160927177429a5e5238c806b1096b76aa6cde6476e71cc5d5a.scope - libcontainer container 0fd214fcb47bfb160927177429a5e5238c806b1096b76aa6cde6476e71cc5d5a. Apr 30 12:53:44.975473 containerd[1477]: time="2025-04-30T12:53:44.975403370Z" level=info msg="StartContainer for \"0fd214fcb47bfb160927177429a5e5238c806b1096b76aa6cde6476e71cc5d5a\" returns successfully" Apr 30 12:53:44.998265 systemd[1]: run-containerd-runc-k8s.io-0fd214fcb47bfb160927177429a5e5238c806b1096b76aa6cde6476e71cc5d5a-runc.e6bUrq.mount: Deactivated successfully. Apr 30 12:53:45.048337 systemd-networkd[1381]: vethb7673f27: Gained IPv6LL Apr 30 12:53:45.266440 kubelet[1759]: E0430 12:53:45.266238 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:45.550777 kubelet[1759]: I0430 12:53:45.550587 1759 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.731736201 podStartE2EDuration="18.550567244s" podCreationTimestamp="2025-04-30 12:53:27 +0000 UTC" firstStartedPulling="2025-04-30 12:53:44.012149471 +0000 UTC m=+47.114505053" lastFinishedPulling="2025-04-30 12:53:44.830980529 +0000 UTC m=+47.933336096" observedRunningTime="2025-04-30 12:53:45.550490661 +0000 UTC m=+48.652846254" watchObservedRunningTime="2025-04-30 12:53:45.550567244 +0000 UTC m=+48.652922833" Apr 30 12:53:46.266796 kubelet[1759]: E0430 12:53:46.266700 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:47.268101 kubelet[1759]: E0430 12:53:47.267999 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:48.269292 kubelet[1759]: E0430 12:53:48.269194 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:49.270230 kubelet[1759]: E0430 12:53:49.270158 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:50.270974 kubelet[1759]: E0430 12:53:50.270890 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 30 12:53:51.271356 kubelet[1759]: E0430 12:53:51.271281 1759 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"