Sep 9 00:31:23.395971 kernel: Linux version 6.6.104-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Mon Sep 8 22:41:17 -00 2025 Sep 9 00:31:23.396010 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=99a67175ee6aabbc03a22dabcade16d60ad192b31c4118a259bf1f24bbfa2d29 Sep 9 00:31:23.396028 kernel: BIOS-provided physical RAM map: Sep 9 00:31:23.396037 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 9 00:31:23.396046 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Sep 9 00:31:23.396055 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Sep 9 00:31:23.396069 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Sep 9 00:31:23.396081 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Sep 9 00:31:23.396092 kernel: BIOS-e820: [mem 0x000000000080c000-0x000000000080ffff] usable Sep 9 00:31:23.396103 kernel: BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS Sep 9 00:31:23.396119 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009c8eefff] usable Sep 9 00:31:23.396131 kernel: BIOS-e820: [mem 0x000000009c8ef000-0x000000009c9eefff] reserved Sep 9 00:31:23.396148 kernel: BIOS-e820: [mem 0x000000009c9ef000-0x000000009caeefff] type 20 Sep 9 00:31:23.396159 kernel: BIOS-e820: [mem 0x000000009caef000-0x000000009cb6efff] reserved Sep 9 00:31:23.396179 kernel: BIOS-e820: [mem 0x000000009cb6f000-0x000000009cb7efff] ACPI data Sep 9 00:31:23.396193 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Sep 9 00:31:23.396208 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009cf3ffff] usable Sep 9 00:31:23.396218 kernel: BIOS-e820: [mem 0x000000009cf40000-0x000000009cf5ffff] reserved Sep 9 00:31:23.396228 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Sep 9 00:31:23.396239 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 9 00:31:23.396249 kernel: NX (Execute Disable) protection: active Sep 9 00:31:23.396259 kernel: APIC: Static calls initialized Sep 9 00:31:23.396270 kernel: efi: EFI v2.7 by EDK II Sep 9 00:31:23.396280 kernel: efi: SMBIOS=0x9c9ab000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b675198 Sep 9 00:31:23.396290 kernel: SMBIOS 2.8 present. Sep 9 00:31:23.396301 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 0.0.0 02/06/2015 Sep 9 00:31:23.396311 kernel: Hypervisor detected: KVM Sep 9 00:31:23.396326 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 9 00:31:23.396336 kernel: kvm-clock: using sched offset of 10251938656 cycles Sep 9 00:31:23.396348 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 9 00:31:23.396358 kernel: tsc: Detected 2794.748 MHz processor Sep 9 00:31:23.396370 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 9 00:31:23.396381 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 9 00:31:23.396391 kernel: last_pfn = 0x9cf40 max_arch_pfn = 0x400000000 Sep 9 00:31:23.396401 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 9 00:31:23.396412 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 9 00:31:23.396426 kernel: Using GB pages for direct mapping Sep 9 00:31:23.396436 kernel: Secure boot disabled Sep 9 00:31:23.396447 kernel: ACPI: Early table checksum verification disabled Sep 9 00:31:23.396457 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Sep 9 00:31:23.396482 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Sep 9 00:31:23.396493 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:31:23.396503 kernel: ACPI: DSDT 0x000000009CB7A000 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:31:23.396531 kernel: ACPI: FACS 0x000000009CBDD000 000040 Sep 9 00:31:23.396544 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:31:23.396557 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:31:23.396567 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:31:23.396578 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:31:23.396588 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Sep 9 00:31:23.396598 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Sep 9 00:31:23.396613 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1b9] Sep 9 00:31:23.396624 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Sep 9 00:31:23.396634 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Sep 9 00:31:23.396644 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Sep 9 00:31:23.396654 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Sep 9 00:31:23.396665 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Sep 9 00:31:23.396674 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Sep 9 00:31:23.396684 kernel: No NUMA configuration found Sep 9 00:31:23.396698 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cf3ffff] Sep 9 00:31:23.396753 kernel: NODE_DATA(0) allocated [mem 0x9cea6000-0x9ceabfff] Sep 9 00:31:23.396764 kernel: Zone ranges: Sep 9 00:31:23.396775 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 9 00:31:23.396785 kernel: DMA32 [mem 0x0000000001000000-0x000000009cf3ffff] Sep 9 00:31:23.396796 kernel: Normal empty Sep 9 00:31:23.396806 kernel: Movable zone start for each node Sep 9 00:31:23.396817 kernel: Early memory node ranges Sep 9 00:31:23.396827 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 9 00:31:23.396836 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Sep 9 00:31:23.396850 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Sep 9 00:31:23.396860 kernel: node 0: [mem 0x000000000080c000-0x000000000080ffff] Sep 9 00:31:23.396869 kernel: node 0: [mem 0x0000000000900000-0x000000009c8eefff] Sep 9 00:31:23.396879 kernel: node 0: [mem 0x000000009cbff000-0x000000009cf3ffff] Sep 9 00:31:23.396893 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cf3ffff] Sep 9 00:31:23.396902 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 9 00:31:23.396912 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 9 00:31:23.396921 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Sep 9 00:31:23.396930 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 9 00:31:23.396939 kernel: On node 0, zone DMA: 240 pages in unavailable ranges Sep 9 00:31:23.396952 kernel: On node 0, zone DMA32: 784 pages in unavailable ranges Sep 9 00:31:23.396962 kernel: On node 0, zone DMA32: 12480 pages in unavailable ranges Sep 9 00:31:23.396971 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 9 00:31:23.396981 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 9 00:31:23.396990 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 9 00:31:23.396999 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 9 00:31:23.397009 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 9 00:31:23.397018 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 9 00:31:23.397027 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 9 00:31:23.397040 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 9 00:31:23.397050 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 9 00:31:23.397059 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 9 00:31:23.397070 kernel: TSC deadline timer available Sep 9 00:31:23.397080 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Sep 9 00:31:23.397091 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 9 00:31:23.397101 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 9 00:31:23.397112 kernel: kvm-guest: setup PV sched yield Sep 9 00:31:23.397122 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices Sep 9 00:31:23.397137 kernel: Booting paravirtualized kernel on KVM Sep 9 00:31:23.397147 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 9 00:31:23.397157 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 9 00:31:23.397167 kernel: percpu: Embedded 58 pages/cpu s197160 r8192 d32216 u524288 Sep 9 00:31:23.397176 kernel: pcpu-alloc: s197160 r8192 d32216 u524288 alloc=1*2097152 Sep 9 00:31:23.397186 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 9 00:31:23.397195 kernel: kvm-guest: PV spinlocks enabled Sep 9 00:31:23.397205 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 9 00:31:23.397216 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=99a67175ee6aabbc03a22dabcade16d60ad192b31c4118a259bf1f24bbfa2d29 Sep 9 00:31:23.397236 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 9 00:31:23.397247 kernel: random: crng init done Sep 9 00:31:23.397256 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 00:31:23.397266 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 9 00:31:23.397276 kernel: Fallback order for Node 0: 0 Sep 9 00:31:23.397286 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629759 Sep 9 00:31:23.397295 kernel: Policy zone: DMA32 Sep 9 00:31:23.397304 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 00:31:23.397318 kernel: Memory: 2400600K/2567000K available (12288K kernel code, 2293K rwdata, 22744K rodata, 42880K init, 2316K bss, 166140K reserved, 0K cma-reserved) Sep 9 00:31:23.397328 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 9 00:31:23.397338 kernel: ftrace: allocating 37969 entries in 149 pages Sep 9 00:31:23.397347 kernel: ftrace: allocated 149 pages with 4 groups Sep 9 00:31:23.397357 kernel: Dynamic Preempt: voluntary Sep 9 00:31:23.397378 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 00:31:23.397391 kernel: rcu: RCU event tracing is enabled. Sep 9 00:31:23.397401 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 9 00:31:23.397411 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 00:31:23.397421 kernel: Rude variant of Tasks RCU enabled. Sep 9 00:31:23.397431 kernel: Tracing variant of Tasks RCU enabled. Sep 9 00:31:23.397441 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 00:31:23.397454 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 9 00:31:23.397473 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 9 00:31:23.397489 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 00:31:23.397507 kernel: Console: colour dummy device 80x25 Sep 9 00:31:23.397518 kernel: printk: console [ttyS0] enabled Sep 9 00:31:23.397534 kernel: ACPI: Core revision 20230628 Sep 9 00:31:23.397545 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 9 00:31:23.397556 kernel: APIC: Switch to symmetric I/O mode setup Sep 9 00:31:23.397567 kernel: x2apic enabled Sep 9 00:31:23.397578 kernel: APIC: Switched APIC routing to: physical x2apic Sep 9 00:31:23.397588 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 9 00:31:23.397599 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 9 00:31:23.397610 kernel: kvm-guest: setup PV IPIs Sep 9 00:31:23.397620 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 9 00:31:23.397635 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Sep 9 00:31:23.397645 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Sep 9 00:31:23.397653 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 9 00:31:23.397661 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 9 00:31:23.397669 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 9 00:31:23.397677 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 9 00:31:23.397685 kernel: Spectre V2 : Mitigation: Retpolines Sep 9 00:31:23.397693 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 9 00:31:23.397718 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 9 00:31:23.397730 kernel: active return thunk: retbleed_return_thunk Sep 9 00:31:23.397740 kernel: RETBleed: Mitigation: untrained return thunk Sep 9 00:31:23.397747 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 9 00:31:23.397755 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 9 00:31:23.397767 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 9 00:31:23.397775 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Sep 9 00:31:23.397783 kernel: active return thunk: srso_return_thunk Sep 9 00:31:23.397791 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 9 00:31:23.397802 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 9 00:31:23.397810 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 9 00:31:23.397818 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 9 00:31:23.397826 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 9 00:31:23.397833 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 9 00:31:23.397841 kernel: Freeing SMP alternatives memory: 32K Sep 9 00:31:23.397849 kernel: pid_max: default: 32768 minimum: 301 Sep 9 00:31:23.397857 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 9 00:31:23.397864 kernel: landlock: Up and running. Sep 9 00:31:23.397875 kernel: SELinux: Initializing. Sep 9 00:31:23.397883 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 00:31:23.397890 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 00:31:23.397898 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 9 00:31:23.397906 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 00:31:23.397914 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 00:31:23.397922 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 00:31:23.397930 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 9 00:31:23.397937 kernel: ... version: 0 Sep 9 00:31:23.397948 kernel: ... bit width: 48 Sep 9 00:31:23.397955 kernel: ... generic registers: 6 Sep 9 00:31:23.397963 kernel: ... value mask: 0000ffffffffffff Sep 9 00:31:23.397971 kernel: ... max period: 00007fffffffffff Sep 9 00:31:23.397978 kernel: ... fixed-purpose events: 0 Sep 9 00:31:23.397992 kernel: ... event mask: 000000000000003f Sep 9 00:31:23.398000 kernel: signal: max sigframe size: 1776 Sep 9 00:31:23.398007 kernel: rcu: Hierarchical SRCU implementation. Sep 9 00:31:23.398016 kernel: rcu: Max phase no-delay instances is 400. Sep 9 00:31:23.398027 kernel: smp: Bringing up secondary CPUs ... Sep 9 00:31:23.398035 kernel: smpboot: x86: Booting SMP configuration: Sep 9 00:31:23.398042 kernel: .... node #0, CPUs: #1 #2 #3 Sep 9 00:31:23.398051 kernel: smp: Brought up 1 node, 4 CPUs Sep 9 00:31:23.398062 kernel: smpboot: Max logical packages: 1 Sep 9 00:31:23.398073 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Sep 9 00:31:23.398083 kernel: devtmpfs: initialized Sep 9 00:31:23.398094 kernel: x86/mm: Memory block size: 128MB Sep 9 00:31:23.398104 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Sep 9 00:31:23.398119 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Sep 9 00:31:23.398130 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) Sep 9 00:31:23.398139 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Sep 9 00:31:23.398147 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Sep 9 00:31:23.398155 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 00:31:23.398163 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 9 00:31:23.398171 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 00:31:23.398179 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 00:31:23.398187 kernel: audit: initializing netlink subsys (disabled) Sep 9 00:31:23.398197 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 00:31:23.398205 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 9 00:31:23.398213 kernel: audit: type=2000 audit(1757377881.428:1): state=initialized audit_enabled=0 res=1 Sep 9 00:31:23.398220 kernel: cpuidle: using governor menu Sep 9 00:31:23.398228 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 00:31:23.398236 kernel: dca service started, version 1.12.1 Sep 9 00:31:23.398244 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Sep 9 00:31:23.398252 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 9 00:31:23.398273 kernel: PCI: Using configuration type 1 for base access Sep 9 00:31:23.398285 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 9 00:31:23.398293 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 00:31:23.398301 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 00:31:23.398318 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 00:31:23.398326 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 00:31:23.398334 kernel: ACPI: Added _OSI(Module Device) Sep 9 00:31:23.398352 kernel: ACPI: Added _OSI(Processor Device) Sep 9 00:31:23.398359 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 00:31:23.398367 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 00:31:23.398379 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Sep 9 00:31:23.398386 kernel: ACPI: Interpreter enabled Sep 9 00:31:23.398394 kernel: ACPI: PM: (supports S0 S3 S5) Sep 9 00:31:23.398402 kernel: ACPI: Using IOAPIC for interrupt routing Sep 9 00:31:23.398410 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 9 00:31:23.398418 kernel: PCI: Using E820 reservations for host bridge windows Sep 9 00:31:23.398425 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 9 00:31:23.398433 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 9 00:31:23.398734 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 9 00:31:23.398892 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 9 00:31:23.399026 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 9 00:31:23.399037 kernel: PCI host bridge to bus 0000:00 Sep 9 00:31:23.399207 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 9 00:31:23.399333 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 9 00:31:23.399452 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 9 00:31:23.399590 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 9 00:31:23.399738 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 9 00:31:23.399868 kernel: pci_bus 0000:00: root bus resource [mem 0x800000000-0xfffffffff window] Sep 9 00:31:23.400003 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 9 00:31:23.400186 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Sep 9 00:31:23.400422 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Sep 9 00:31:23.400645 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] Sep 9 00:31:23.400876 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] Sep 9 00:31:23.401081 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] Sep 9 00:31:23.401273 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Sep 9 00:31:23.401447 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 9 00:31:23.401690 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Sep 9 00:31:23.401898 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] Sep 9 00:31:23.402089 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] Sep 9 00:31:23.402290 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x800000000-0x800003fff 64bit pref] Sep 9 00:31:23.402515 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Sep 9 00:31:23.402695 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] Sep 9 00:31:23.402908 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] Sep 9 00:31:23.404022 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x800004000-0x800007fff 64bit pref] Sep 9 00:31:23.404275 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Sep 9 00:31:23.404512 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] Sep 9 00:31:23.404720 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] Sep 9 00:31:23.404910 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x800008000-0x80000bfff 64bit pref] Sep 9 00:31:23.405103 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] Sep 9 00:31:23.405338 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Sep 9 00:31:23.405544 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 9 00:31:23.405769 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Sep 9 00:31:23.405966 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] Sep 9 00:31:23.406158 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] Sep 9 00:31:23.406377 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Sep 9 00:31:23.406589 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] Sep 9 00:31:23.406610 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 9 00:31:23.406623 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 9 00:31:23.406635 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 9 00:31:23.406654 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 9 00:31:23.406666 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 9 00:31:23.406679 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 9 00:31:23.406691 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 9 00:31:23.406722 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 9 00:31:23.406735 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 9 00:31:23.406747 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 9 00:31:23.406759 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 9 00:31:23.406772 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 9 00:31:23.406789 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 9 00:31:23.406802 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 9 00:31:23.406813 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 9 00:31:23.406825 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 9 00:31:23.406838 kernel: iommu: Default domain type: Translated Sep 9 00:31:23.406850 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 9 00:31:23.406863 kernel: efivars: Registered efivars operations Sep 9 00:31:23.406875 kernel: PCI: Using ACPI for IRQ routing Sep 9 00:31:23.406888 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 9 00:31:23.406900 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Sep 9 00:31:23.406918 kernel: e820: reserve RAM buffer [mem 0x00810000-0x008fffff] Sep 9 00:31:23.406930 kernel: e820: reserve RAM buffer [mem 0x9c8ef000-0x9fffffff] Sep 9 00:31:23.406942 kernel: e820: reserve RAM buffer [mem 0x9cf40000-0x9fffffff] Sep 9 00:31:23.407155 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 9 00:31:23.407354 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 9 00:31:23.407560 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 9 00:31:23.407580 kernel: vgaarb: loaded Sep 9 00:31:23.407593 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 9 00:31:23.407611 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 9 00:31:23.407624 kernel: clocksource: Switched to clocksource kvm-clock Sep 9 00:31:23.407635 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 00:31:23.407647 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 00:31:23.407658 kernel: pnp: PnP ACPI init Sep 9 00:31:23.407896 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 9 00:31:23.407917 kernel: pnp: PnP ACPI: found 6 devices Sep 9 00:31:23.407929 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 9 00:31:23.407947 kernel: NET: Registered PF_INET protocol family Sep 9 00:31:23.407958 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 9 00:31:23.407969 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 9 00:31:23.407982 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 00:31:23.407993 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 9 00:31:23.408005 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 9 00:31:23.408016 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 9 00:31:23.408027 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 00:31:23.408038 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 00:31:23.408054 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 00:31:23.408066 kernel: NET: Registered PF_XDP protocol family Sep 9 00:31:23.408253 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window Sep 9 00:31:23.408434 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] Sep 9 00:31:23.408639 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 9 00:31:23.408832 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 9 00:31:23.408995 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 9 00:31:23.409159 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 9 00:31:23.409391 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 9 00:31:23.409565 kernel: pci_bus 0000:00: resource 9 [mem 0x800000000-0xfffffffff window] Sep 9 00:31:23.409583 kernel: PCI: CLS 0 bytes, default 64 Sep 9 00:31:23.409595 kernel: Initialise system trusted keyrings Sep 9 00:31:23.409607 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 9 00:31:23.409618 kernel: Key type asymmetric registered Sep 9 00:31:23.409629 kernel: Asymmetric key parser 'x509' registered Sep 9 00:31:23.409640 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Sep 9 00:31:23.409651 kernel: io scheduler mq-deadline registered Sep 9 00:31:23.409667 kernel: io scheduler kyber registered Sep 9 00:31:23.409677 kernel: io scheduler bfq registered Sep 9 00:31:23.409687 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 9 00:31:23.409715 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 9 00:31:23.409728 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 9 00:31:23.409740 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 9 00:31:23.409752 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 00:31:23.409765 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 9 00:31:23.409778 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 9 00:31:23.409795 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 9 00:31:23.409807 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 9 00:31:23.410856 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 9 00:31:23.410938 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 9 00:31:23.411209 kernel: rtc_cmos 00:04: registered as rtc0 Sep 9 00:31:23.411381 kernel: rtc_cmos 00:04: setting system clock to 2025-09-09T00:31:22 UTC (1757377882) Sep 9 00:31:23.411577 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 9 00:31:23.411596 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 9 00:31:23.411616 kernel: efifb: probing for efifb Sep 9 00:31:23.411627 kernel: efifb: framebuffer at 0xc0000000, using 1408k, total 1408k Sep 9 00:31:23.411639 kernel: efifb: mode is 800x600x24, linelength=2400, pages=1 Sep 9 00:31:23.411651 kernel: efifb: scrolling: redraw Sep 9 00:31:23.411663 kernel: efifb: Truecolor: size=0:8:8:8, shift=0:16:8:0 Sep 9 00:31:23.411673 kernel: Console: switching to colour frame buffer device 100x37 Sep 9 00:31:23.411726 kernel: fb0: EFI VGA frame buffer device Sep 9 00:31:23.411742 kernel: pstore: Using crash dump compression: deflate Sep 9 00:31:23.411754 kernel: pstore: Registered efi_pstore as persistent store backend Sep 9 00:31:23.411767 kernel: NET: Registered PF_INET6 protocol family Sep 9 00:31:23.411777 kernel: Segment Routing with IPv6 Sep 9 00:31:23.411787 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 00:31:23.411797 kernel: NET: Registered PF_PACKET protocol family Sep 9 00:31:23.411807 kernel: Key type dns_resolver registered Sep 9 00:31:23.411818 kernel: IPI shorthand broadcast: enabled Sep 9 00:31:23.411830 kernel: sched_clock: Marking stable (2338007096, 253219713)->(2775966570, -184739761) Sep 9 00:31:23.411843 kernel: registered taskstats version 1 Sep 9 00:31:23.411855 kernel: Loading compiled-in X.509 certificates Sep 9 00:31:23.411871 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.104-flatcar: cc5240ef94b546331b2896cdc739274c03278c51' Sep 9 00:31:23.411883 kernel: Key type .fscrypt registered Sep 9 00:31:23.411895 kernel: Key type fscrypt-provisioning registered Sep 9 00:31:23.411906 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 00:31:23.411917 kernel: ima: Allocated hash algorithm: sha1 Sep 9 00:31:23.411928 kernel: ima: No architecture policies found Sep 9 00:31:23.411939 kernel: clk: Disabling unused clocks Sep 9 00:31:23.411951 kernel: Freeing unused kernel image (initmem) memory: 42880K Sep 9 00:31:23.411963 kernel: Write protecting the kernel read-only data: 36864k Sep 9 00:31:23.411979 kernel: Freeing unused kernel image (rodata/data gap) memory: 1832K Sep 9 00:31:23.411991 kernel: Run /init as init process Sep 9 00:31:23.412003 kernel: with arguments: Sep 9 00:31:23.412015 kernel: /init Sep 9 00:31:23.412027 kernel: with environment: Sep 9 00:31:23.412039 kernel: HOME=/ Sep 9 00:31:23.412051 kernel: TERM=linux Sep 9 00:31:23.412064 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 00:31:23.412084 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 9 00:31:23.412102 systemd[1]: Detected virtualization kvm. Sep 9 00:31:23.412117 systemd[1]: Detected architecture x86-64. Sep 9 00:31:23.412132 systemd[1]: Running in initrd. Sep 9 00:31:23.412154 systemd[1]: No hostname configured, using default hostname. Sep 9 00:31:23.412167 systemd[1]: Hostname set to . Sep 9 00:31:23.412181 systemd[1]: Initializing machine ID from VM UUID. Sep 9 00:31:23.412195 systemd[1]: Queued start job for default target initrd.target. Sep 9 00:31:23.412210 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 00:31:23.412224 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 00:31:23.412240 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 00:31:23.412254 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 00:31:23.412274 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 00:31:23.412289 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 00:31:23.412306 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 9 00:31:23.412321 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 00:31:23.412335 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 00:31:23.412349 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 00:31:23.412363 systemd[1]: Reached target paths.target - Path Units. Sep 9 00:31:23.412382 systemd[1]: Reached target slices.target - Slice Units. Sep 9 00:31:23.412395 systemd[1]: Reached target swap.target - Swaps. Sep 9 00:31:23.412409 systemd[1]: Reached target timers.target - Timer Units. Sep 9 00:31:23.412424 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 00:31:23.412437 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 00:31:23.412450 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 00:31:23.412463 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 9 00:31:23.412486 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 00:31:23.412500 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 00:31:23.412519 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 00:31:23.412533 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 00:31:23.412545 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 00:31:23.412558 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 00:31:23.412571 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 00:31:23.412583 systemd[1]: Starting systemd-fsck-usr.service... Sep 9 00:31:23.412595 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 00:31:23.412608 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 00:31:23.412626 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:31:23.412639 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 00:31:23.412653 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 00:31:23.412666 systemd[1]: Finished systemd-fsck-usr.service. Sep 9 00:31:23.412741 systemd-journald[191]: Collecting audit messages is disabled. Sep 9 00:31:23.412778 systemd-journald[191]: Journal started Sep 9 00:31:23.412804 systemd-journald[191]: Runtime Journal (/run/log/journal/60966e25262345e2bfdf9cf7cb5f1550) is 6.0M, max 48.3M, 42.2M free. Sep 9 00:31:23.421353 systemd-modules-load[193]: Inserted module 'overlay' Sep 9 00:31:23.425014 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 00:31:23.430022 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 00:31:23.434032 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 00:31:23.438439 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:31:23.444399 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 00:31:23.454545 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 00:31:23.458971 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 00:31:23.491985 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 00:31:23.502677 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:31:23.511002 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 00:31:23.512747 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 00:31:23.539726 dracut-cmdline[219]: dracut-dracut-053 Sep 9 00:31:23.547747 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 9 00:31:23.555109 dracut-cmdline[219]: 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,115200 flatcar.first_boot=detected verity.usrhash=99a67175ee6aabbc03a22dabcade16d60ad192b31c4118a259bf1f24bbfa2d29 Sep 9 00:31:23.578396 kernel: Bridge firewalling registered Sep 9 00:31:23.576311 systemd-modules-load[193]: Inserted module 'br_netfilter' Sep 9 00:31:23.580918 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 00:31:23.598978 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 00:31:23.635948 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 00:31:23.646835 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 00:31:23.710452 systemd-resolved[253]: Positive Trust Anchors: Sep 9 00:31:23.711788 systemd-resolved[253]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 00:31:23.712049 systemd-resolved[253]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 00:31:23.721530 systemd-resolved[253]: Defaulting to hostname 'linux'. Sep 9 00:31:23.724007 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 00:31:23.733041 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 00:31:23.861546 kernel: SCSI subsystem initialized Sep 9 00:31:23.877753 kernel: Loading iSCSI transport class v2.0-870. Sep 9 00:31:23.905082 kernel: iscsi: registered transport (tcp) Sep 9 00:31:23.961160 kernel: iscsi: registered transport (qla4xxx) Sep 9 00:31:23.961251 kernel: QLogic iSCSI HBA Driver Sep 9 00:31:24.152092 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 00:31:24.181982 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 00:31:24.228536 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 00:31:24.228642 kernel: device-mapper: uevent: version 1.0.3 Sep 9 00:31:24.230055 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 9 00:31:24.299178 kernel: raid6: avx2x4 gen() 19180 MB/s Sep 9 00:31:24.315763 kernel: raid6: avx2x2 gen() 17184 MB/s Sep 9 00:31:24.335505 kernel: raid6: avx2x1 gen() 14816 MB/s Sep 9 00:31:24.335604 kernel: raid6: using algorithm avx2x4 gen() 19180 MB/s Sep 9 00:31:24.352851 kernel: raid6: .... xor() 4753 MB/s, rmw enabled Sep 9 00:31:24.352971 kernel: raid6: using avx2x2 recovery algorithm Sep 9 00:31:24.395343 kernel: xor: automatically using best checksumming function avx Sep 9 00:31:24.717749 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 00:31:24.739927 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 00:31:24.769474 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 00:31:24.786351 systemd-udevd[413]: Using default interface naming scheme 'v255'. Sep 9 00:31:24.796065 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 00:31:24.868991 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 00:31:24.918363 dracut-pre-trigger[416]: rd.md=0: removing MD RAID activation Sep 9 00:31:24.994376 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 00:31:25.022954 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 00:31:25.153825 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 00:31:25.211168 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 00:31:25.234949 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 00:31:25.240270 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 00:31:25.245677 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 00:31:25.248857 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 00:31:25.265580 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 9 00:31:25.267356 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 00:31:25.271394 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 9 00:31:25.272693 kernel: cryptd: max_cpu_qlen set to 1000 Sep 9 00:31:25.287770 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 00:31:25.298509 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 00:31:25.307353 kernel: libata version 3.00 loaded. Sep 9 00:31:25.298742 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:31:25.315749 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 00:31:25.317188 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 00:31:25.318659 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 00:31:25.318934 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:31:25.320510 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:31:25.360326 kernel: AVX2 version of gcm_enc/dec engaged. Sep 9 00:31:25.387012 kernel: AES CTR mode by8 optimization enabled Sep 9 00:31:25.390795 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:31:25.407150 kernel: ahci 0000:00:1f.2: version 3.0 Sep 9 00:31:25.409122 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 9 00:31:25.409149 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Sep 9 00:31:25.412828 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 9 00:31:25.413078 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (462) Sep 9 00:31:25.419281 kernel: scsi host0: ahci Sep 9 00:31:25.424873 kernel: scsi host1: ahci Sep 9 00:31:25.429512 kernel: BTRFS: device fsid 7cd16ef1-c91b-4e35-a9b3-a431b3c1949a devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (459) Sep 9 00:31:25.430730 kernel: scsi host2: ahci Sep 9 00:31:25.432984 kernel: scsi host3: ahci Sep 9 00:31:25.438736 kernel: scsi host4: ahci Sep 9 00:31:25.447749 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 9 00:31:25.459024 kernel: scsi host5: ahci Sep 9 00:31:25.459345 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Sep 9 00:31:25.459365 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Sep 9 00:31:25.464208 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Sep 9 00:31:25.464256 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Sep 9 00:31:25.464284 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Sep 9 00:31:25.464301 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Sep 9 00:31:25.484217 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 9 00:31:25.495666 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 00:31:25.504892 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 9 00:31:25.509165 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 9 00:31:25.535089 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 00:31:25.540881 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 00:31:25.541029 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:31:25.542591 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:31:25.548264 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:31:25.571939 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 00:31:25.585551 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 00:31:25.604049 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:31:25.631678 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 00:31:25.719208 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:31:25.779745 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 9 00:31:25.790366 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 9 00:31:25.790486 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 9 00:31:25.796832 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 9 00:31:25.800967 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 9 00:31:25.801041 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 9 00:31:25.801058 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 9 00:31:25.802284 kernel: ata3.00: applying bridge limits Sep 9 00:31:25.809965 kernel: ata3.00: configured for UDMA/100 Sep 9 00:31:25.821851 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 9 00:31:25.970315 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 9 00:31:25.970739 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 9 00:31:25.990767 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 9 00:31:26.767753 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 00:31:26.775232 disk-uuid[556]: The operation has completed successfully. Sep 9 00:31:26.896896 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 00:31:26.897090 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 00:31:26.912785 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 9 00:31:26.925383 sh[598]: Success Sep 9 00:31:26.967841 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Sep 9 00:31:27.112871 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 9 00:31:27.118887 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 9 00:31:27.130984 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 9 00:31:27.172064 kernel: BTRFS info (device dm-0): first mount of filesystem 7cd16ef1-c91b-4e35-a9b3-a431b3c1949a Sep 9 00:31:27.172138 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 9 00:31:27.172158 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 9 00:31:27.195323 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 9 00:31:27.195470 kernel: BTRFS info (device dm-0): using free space tree Sep 9 00:31:27.246340 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 9 00:31:27.252011 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 9 00:31:27.314040 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 9 00:31:27.349124 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 00:31:27.417442 kernel: BTRFS info (device vda6): first mount of filesystem a5263def-4663-4ce6-b873-45a7d7f1ec33 Sep 9 00:31:27.417534 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 00:31:27.417552 kernel: BTRFS info (device vda6): using free space tree Sep 9 00:31:27.458982 kernel: BTRFS info (device vda6): auto enabling async discard Sep 9 00:31:27.495514 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 9 00:31:27.510612 kernel: BTRFS info (device vda6): last unmount of filesystem a5263def-4663-4ce6-b873-45a7d7f1ec33 Sep 9 00:31:27.755519 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 00:31:27.768480 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 9 00:31:27.782021 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 00:31:27.785642 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 00:31:27.856974 systemd-networkd[779]: lo: Link UP Sep 9 00:31:27.856994 systemd-networkd[779]: lo: Gained carrier Sep 9 00:31:27.864378 systemd-networkd[779]: Enumeration completed Sep 9 00:31:27.864575 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 00:31:27.871374 systemd-networkd[779]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:31:27.871382 systemd-networkd[779]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 00:31:27.872696 systemd[1]: Reached target network.target - Network. Sep 9 00:31:27.878580 systemd-networkd[779]: eth0: Link UP Sep 9 00:31:27.878587 systemd-networkd[779]: eth0: Gained carrier Sep 9 00:31:27.878603 systemd-networkd[779]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:31:27.930144 systemd-networkd[779]: eth0: DHCPv4 address 10.0.0.110/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 00:31:27.979187 ignition[778]: Ignition 2.19.0 Sep 9 00:31:27.979209 ignition[778]: Stage: fetch-offline Sep 9 00:31:27.979270 ignition[778]: no configs at "/usr/lib/ignition/base.d" Sep 9 00:31:27.979287 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:31:27.979442 ignition[778]: parsed url from cmdline: "" Sep 9 00:31:27.979448 ignition[778]: no config URL provided Sep 9 00:31:27.979456 ignition[778]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 00:31:27.979471 ignition[778]: no config at "/usr/lib/ignition/user.ign" Sep 9 00:31:27.979514 ignition[778]: op(1): [started] loading QEMU firmware config module Sep 9 00:31:27.979522 ignition[778]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 9 00:31:28.022407 ignition[778]: op(1): [finished] loading QEMU firmware config module Sep 9 00:31:28.029545 ignition[778]: parsing config with SHA512: d3959a382eda2cf5c6557feb8b6dd9203a6cb4a1709201472d28502aec7e12bb5e1b137bae43151e247ec66b390d2b0b81c33a37453f6741c59ee6df80086c16 Sep 9 00:31:28.226689 unknown[778]: fetched base config from "system" Sep 9 00:31:28.226957 ignition[778]: fetch-offline: fetch-offline passed Sep 9 00:31:28.226726 unknown[778]: fetched user config from "qemu" Sep 9 00:31:28.227076 ignition[778]: Ignition finished successfully Sep 9 00:31:28.235530 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 00:31:28.240143 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 9 00:31:28.248985 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 00:31:28.303766 ignition[791]: Ignition 2.19.0 Sep 9 00:31:28.305797 ignition[791]: Stage: kargs Sep 9 00:31:28.307227 ignition[791]: no configs at "/usr/lib/ignition/base.d" Sep 9 00:31:28.307246 ignition[791]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:31:28.308133 ignition[791]: kargs: kargs passed Sep 9 00:31:28.308194 ignition[791]: Ignition finished successfully Sep 9 00:31:28.316623 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 00:31:28.329110 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 00:31:28.351205 ignition[800]: Ignition 2.19.0 Sep 9 00:31:28.351224 ignition[800]: Stage: disks Sep 9 00:31:28.351488 ignition[800]: no configs at "/usr/lib/ignition/base.d" Sep 9 00:31:28.351506 ignition[800]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:31:28.352427 ignition[800]: disks: disks passed Sep 9 00:31:28.352580 ignition[800]: Ignition finished successfully Sep 9 00:31:28.359586 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 00:31:28.366107 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 00:31:28.368019 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 00:31:28.369230 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 00:31:28.376087 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 00:31:28.392958 systemd[1]: Reached target basic.target - Basic System. Sep 9 00:31:28.413806 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 00:31:28.464096 systemd-fsck[812]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 9 00:31:28.484125 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 00:31:28.504561 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 00:31:28.797761 kernel: EXT4-fs (vda9): mounted filesystem ee55a213-d578-493d-a79b-e10c399cd35c r/w with ordered data mode. Quota mode: none. Sep 9 00:31:28.798991 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 00:31:28.809574 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 00:31:28.827968 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 00:31:28.831685 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 00:31:28.835859 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 9 00:31:28.835949 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 9 00:31:28.835995 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 00:31:28.852369 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (820) Sep 9 00:31:28.851620 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 00:31:28.858513 kernel: BTRFS info (device vda6): first mount of filesystem a5263def-4663-4ce6-b873-45a7d7f1ec33 Sep 9 00:31:28.858545 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 00:31:28.858560 kernel: BTRFS info (device vda6): using free space tree Sep 9 00:31:28.868043 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 00:31:28.898190 kernel: BTRFS info (device vda6): auto enabling async discard Sep 9 00:31:28.909245 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 00:31:29.032984 initrd-setup-root[844]: cut: /sysroot/etc/passwd: No such file or directory Sep 9 00:31:29.058247 initrd-setup-root[851]: cut: /sysroot/etc/group: No such file or directory Sep 9 00:31:29.096444 initrd-setup-root[858]: cut: /sysroot/etc/shadow: No such file or directory Sep 9 00:31:29.109801 initrd-setup-root[865]: cut: /sysroot/etc/gshadow: No such file or directory Sep 9 00:31:29.483158 systemd-networkd[779]: eth0: Gained IPv6LL Sep 9 00:31:29.574160 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 00:31:29.597989 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 00:31:29.604091 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 9 00:31:29.619111 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 9 00:31:29.621207 kernel: BTRFS info (device vda6): last unmount of filesystem a5263def-4663-4ce6-b873-45a7d7f1ec33 Sep 9 00:31:29.676824 ignition[932]: INFO : Ignition 2.19.0 Sep 9 00:31:29.676824 ignition[932]: INFO : Stage: mount Sep 9 00:31:29.676824 ignition[932]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 00:31:29.676824 ignition[932]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:31:29.688107 ignition[932]: INFO : mount: mount passed Sep 9 00:31:29.688107 ignition[932]: INFO : Ignition finished successfully Sep 9 00:31:29.677307 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 9 00:31:29.683427 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 00:31:29.691970 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 00:31:29.822027 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 00:31:29.861770 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (946) Sep 9 00:31:29.869367 kernel: BTRFS info (device vda6): first mount of filesystem a5263def-4663-4ce6-b873-45a7d7f1ec33 Sep 9 00:31:29.869458 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 00:31:29.869481 kernel: BTRFS info (device vda6): using free space tree Sep 9 00:31:29.911467 kernel: BTRFS info (device vda6): auto enabling async discard Sep 9 00:31:29.922133 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 00:31:30.073361 ignition[962]: INFO : Ignition 2.19.0 Sep 9 00:31:30.073361 ignition[962]: INFO : Stage: files Sep 9 00:31:30.076619 ignition[962]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 00:31:30.076619 ignition[962]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:31:30.076619 ignition[962]: DEBUG : files: compiled without relabeling support, skipping Sep 9 00:31:30.082060 ignition[962]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 00:31:30.082060 ignition[962]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 00:31:30.086004 ignition[962]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 00:31:30.086004 ignition[962]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 00:31:30.089556 ignition[962]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 00:31:30.087470 unknown[962]: wrote ssh authorized keys file for user: core Sep 9 00:31:30.093230 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 00:31:30.093230 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 00:31:30.093230 ignition[962]: INFO : files: op(4): [started] processing unit "etcd-member.service" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Sep 9 00:31:30.101060 ignition[962]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Sep 9 00:31:30.285819 ignition[962]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 00:31:30.389589 ignition[962]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 00:31:30.389589 ignition[962]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Sep 9 00:31:30.389589 ignition[962]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Sep 9 00:31:30.389589 ignition[962]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Sep 9 00:31:30.407887 ignition[962]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 00:31:30.407887 ignition[962]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 00:31:30.407887 ignition[962]: INFO : files: files passed Sep 9 00:31:30.407887 ignition[962]: INFO : Ignition finished successfully Sep 9 00:31:30.415566 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 00:31:30.429995 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 00:31:30.433333 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 00:31:30.441389 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 00:31:30.441689 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 00:31:30.457534 initrd-setup-root-after-ignition[990]: grep: /sysroot/oem/oem-release: No such file or directory Sep 9 00:31:30.459363 initrd-setup-root-after-ignition[993]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 00:31:30.459363 initrd-setup-root-after-ignition[993]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 00:31:30.465801 initrd-setup-root-after-ignition[997]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 00:31:30.461825 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 00:31:30.464694 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 9 00:31:30.483063 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 00:31:30.552122 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 00:31:30.553801 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 00:31:30.568616 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 00:31:30.570739 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 9 00:31:30.572314 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 9 00:31:30.601209 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 00:31:30.641646 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 00:31:30.699237 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 00:31:30.745124 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 00:31:30.755233 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 00:31:30.764637 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 00:31:30.766096 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 00:31:30.766294 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 00:31:30.768090 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 9 00:31:30.772640 systemd[1]: Stopped target basic.target - Basic System. Sep 9 00:31:30.782264 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 9 00:31:30.788381 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 00:31:30.793280 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 00:31:30.793643 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 00:31:30.793782 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 00:31:30.793918 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 00:31:30.794040 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 00:31:30.794162 systemd[1]: Stopped target swap.target - Swaps. Sep 9 00:31:30.794248 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 00:31:30.794440 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 00:31:30.794752 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 00:31:30.794880 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 00:31:30.794959 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 00:31:30.826714 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 00:31:30.847191 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 00:31:30.847434 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 00:31:30.911931 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 00:31:30.912121 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 00:31:30.915345 systemd[1]: Stopped target paths.target - Path Units. Sep 9 00:31:30.929820 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 00:31:30.933833 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 00:31:30.934312 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 00:31:30.958214 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 00:31:30.963958 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 00:31:30.964106 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 00:31:30.969505 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 00:31:30.972422 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 00:31:30.992878 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 00:31:30.994858 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 00:31:31.003551 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 00:31:31.005163 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 00:31:31.046499 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 00:31:31.060654 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 9 00:31:31.090200 ignition[1017]: INFO : Ignition 2.19.0 Sep 9 00:31:31.090200 ignition[1017]: INFO : Stage: umount Sep 9 00:31:31.090200 ignition[1017]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 00:31:31.090200 ignition[1017]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:31:31.090200 ignition[1017]: INFO : umount: umount passed Sep 9 00:31:31.090200 ignition[1017]: INFO : Ignition finished successfully Sep 9 00:31:31.102465 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 00:31:31.102771 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 00:31:31.103987 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 00:31:31.104131 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 00:31:31.112831 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 00:31:31.113024 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 00:31:31.120254 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 00:31:31.120460 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 00:31:31.178122 systemd[1]: Stopped target network.target - Network. Sep 9 00:31:31.182454 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 00:31:31.182569 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 00:31:31.189651 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 00:31:31.189769 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 00:31:31.228546 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 00:31:31.228650 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 9 00:31:31.234296 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 00:31:31.234397 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 00:31:31.236670 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 9 00:31:31.263817 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 00:31:31.278453 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 9 00:31:31.289859 systemd-networkd[779]: eth0: DHCPv6 lease lost Sep 9 00:31:31.292957 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 00:31:31.293153 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 00:31:31.296359 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 00:31:31.296748 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 9 00:31:31.321848 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 00:31:31.322022 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 9 00:31:31.330089 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 00:31:31.330183 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 9 00:31:31.332613 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 00:31:31.332696 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 00:31:31.353907 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 00:31:31.356033 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 00:31:31.356173 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 00:31:31.358168 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 00:31:31.358264 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 9 00:31:31.361577 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 00:31:31.361653 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 00:31:31.361999 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 00:31:31.362162 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 00:31:31.365411 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 00:31:31.390229 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 00:31:31.390505 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 00:31:31.397391 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 00:31:31.397533 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 00:31:31.399444 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 00:31:31.399504 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 00:31:31.402555 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 00:31:31.402652 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 00:31:31.434568 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 00:31:31.434677 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 00:31:31.439298 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 00:31:31.439430 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:31:31.477403 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 00:31:31.482983 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 00:31:31.483161 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 00:31:31.486407 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 9 00:31:31.486496 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 00:31:31.495821 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 00:31:31.495922 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 00:31:31.499340 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 00:31:31.499438 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:31:31.503284 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 00:31:31.503604 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 00:31:31.513468 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 00:31:31.513661 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 00:31:31.523561 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 00:31:31.542550 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 00:31:31.566408 systemd[1]: Switching root. Sep 9 00:31:31.622511 systemd-journald[191]: Journal stopped Sep 9 00:31:33.983256 systemd-journald[191]: Received SIGTERM from PID 1 (systemd). Sep 9 00:31:33.983357 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 00:31:33.983377 kernel: SELinux: policy capability open_perms=1 Sep 9 00:31:33.983394 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 00:31:33.983410 kernel: SELinux: policy capability always_check_network=0 Sep 9 00:31:33.983426 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 00:31:33.983442 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 00:31:33.983472 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 00:31:33.983488 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 00:31:33.983507 kernel: audit: type=1403 audit(1757377891.807:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 00:31:33.983529 systemd[1]: Successfully loaded SELinux policy in 69.984ms. Sep 9 00:31:33.983562 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 22.859ms. Sep 9 00:31:33.983581 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 9 00:31:33.983598 systemd[1]: Detected virtualization kvm. Sep 9 00:31:33.983616 systemd[1]: Detected architecture x86-64. Sep 9 00:31:33.983633 systemd[1]: Detected first boot. Sep 9 00:31:33.983650 systemd[1]: Initializing machine ID from VM UUID. Sep 9 00:31:33.983668 zram_generator::config[1062]: No configuration found. Sep 9 00:31:33.983691 systemd[1]: Populated /etc with preset unit settings. Sep 9 00:31:33.983728 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 00:31:33.983757 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 00:31:33.983775 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 00:31:33.983793 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 00:31:33.983814 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 00:31:33.983832 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 00:31:33.983850 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 00:31:33.983886 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 00:31:33.983903 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 00:31:33.983920 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 00:31:33.983945 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 00:31:33.983963 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 00:31:33.983992 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 00:31:33.984011 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 00:31:33.984029 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 00:31:33.984047 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 00:31:33.984065 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 00:31:33.984082 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 9 00:31:33.984100 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 00:31:33.984117 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 00:31:33.984134 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 00:31:33.984160 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 00:31:33.987643 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 00:31:33.987700 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 00:31:33.987737 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 00:31:33.987756 systemd[1]: Reached target slices.target - Slice Units. Sep 9 00:31:33.987774 systemd[1]: Reached target swap.target - Swaps. Sep 9 00:31:33.987793 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 00:31:33.987828 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 00:31:33.987857 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 00:31:33.987876 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 00:31:33.987895 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 00:31:33.987913 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 00:31:33.987933 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 00:31:33.987952 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 00:31:33.987971 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 00:31:33.987990 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 00:31:33.988009 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 00:31:33.988032 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 00:31:33.988051 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 00:31:33.988070 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 9 00:31:33.988089 systemd[1]: Reached target machines.target - Containers. Sep 9 00:31:33.988108 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 00:31:33.988127 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 00:31:33.988146 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 00:31:33.988165 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 00:31:33.988200 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 00:31:33.988220 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 00:31:33.988239 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 00:31:33.988270 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 00:31:33.988289 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 00:31:33.988307 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 9 00:31:33.988326 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 00:31:33.988345 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 00:31:33.988363 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 00:31:33.988386 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 00:31:33.988406 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 00:31:33.988424 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 00:31:33.988442 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 00:31:33.988460 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 00:31:33.988477 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 00:31:33.988495 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 00:31:33.988513 systemd[1]: Stopped verity-setup.service. Sep 9 00:31:33.988531 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 00:31:33.988559 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 00:31:33.988576 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 00:31:33.988593 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 00:31:33.988610 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 00:31:33.988628 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 00:31:33.988649 kernel: loop: module loaded Sep 9 00:31:33.988677 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 00:31:33.988695 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 00:31:33.988729 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 00:31:33.988748 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 00:31:33.988811 systemd-journald[1132]: Collecting audit messages is disabled. Sep 9 00:31:33.988844 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 00:31:33.988867 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 00:31:33.988885 systemd-journald[1132]: Journal started Sep 9 00:31:33.988915 systemd-journald[1132]: Runtime Journal (/run/log/journal/60966e25262345e2bfdf9cf7cb5f1550) is 6.0M, max 48.3M, 42.2M free. Sep 9 00:31:33.247729 systemd[1]: Queued start job for default target multi-user.target. Sep 9 00:31:33.291078 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 9 00:31:33.293851 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 00:31:33.295210 systemd[1]: systemd-journald.service: Consumed 1.203s CPU time. Sep 9 00:31:33.996333 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 00:31:34.001830 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 00:31:34.002164 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 00:31:34.007476 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 00:31:34.011165 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 00:31:34.018171 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 00:31:34.051348 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 00:31:34.081007 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 00:31:34.084651 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 9 00:31:34.084727 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 00:31:34.092842 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 9 00:31:34.106214 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 00:31:34.128115 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 00:31:34.133634 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 00:31:34.159936 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 00:31:34.181010 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 00:31:34.185388 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 00:31:34.191532 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 00:31:34.434638 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 00:31:34.459481 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 00:31:34.490500 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 00:31:34.507742 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 00:31:34.522854 kernel: fuse: init (API version 7.39) Sep 9 00:31:34.572866 systemd-journald[1132]: Time spent on flushing to /var/log/journal/60966e25262345e2bfdf9cf7cb5f1550 is 29.489ms for 962 entries. Sep 9 00:31:34.572866 systemd-journald[1132]: System Journal (/var/log/journal/60966e25262345e2bfdf9cf7cb5f1550) is 8.0M, max 195.6M, 187.6M free. Sep 9 00:31:34.838757 systemd-journald[1132]: Received client request to flush runtime journal. Sep 9 00:31:34.838847 kernel: ACPI: bus type drm_connector registered Sep 9 00:31:34.838890 kernel: loop0: detected capacity change from 0 to 140768 Sep 9 00:31:34.563138 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 00:31:34.578099 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 00:31:34.579912 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 00:31:34.629109 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 00:31:34.649306 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 00:31:34.670965 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 9 00:31:34.689373 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 9 00:31:34.709462 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 00:31:34.709777 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 00:31:34.712564 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 00:31:34.713135 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 00:31:34.718305 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 00:31:34.719190 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 00:31:34.742596 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 00:31:34.756385 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 00:31:34.760631 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 00:31:34.765011 udevadm[1181]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 9 00:31:34.845794 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 00:31:34.865913 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 00:31:34.916735 systemd-tmpfiles[1172]: ACLs are not supported, ignoring. Sep 9 00:31:34.916762 systemd-tmpfiles[1172]: ACLs are not supported, ignoring. Sep 9 00:31:34.942691 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 00:31:34.962230 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 00:31:34.976581 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 00:31:35.076117 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 00:31:35.078004 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 9 00:31:35.090300 kernel: loop1: detected capacity change from 0 to 142488 Sep 9 00:31:35.134275 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 00:31:35.157301 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 00:31:35.219132 systemd-tmpfiles[1198]: ACLs are not supported, ignoring. Sep 9 00:31:35.219169 systemd-tmpfiles[1198]: ACLs are not supported, ignoring. Sep 9 00:31:35.255462 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 00:31:35.279223 kernel: loop2: detected capacity change from 0 to 140768 Sep 9 00:31:35.365445 kernel: loop3: detected capacity change from 0 to 142488 Sep 9 00:31:35.472653 (sd-merge)[1202]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 9 00:31:35.473484 (sd-merge)[1202]: Merged extensions into '/usr'. Sep 9 00:31:35.591212 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 00:31:35.609478 systemd[1]: Starting ensure-sysext.service... Sep 9 00:31:35.616048 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 00:31:35.657621 systemd[1]: Reloading requested from client PID 1204 ('systemctl') (unit ensure-sysext.service)... Sep 9 00:31:35.657646 systemd[1]: Reloading... Sep 9 00:31:35.727211 systemd-tmpfiles[1205]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 00:31:35.727638 systemd-tmpfiles[1205]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 9 00:31:35.728887 systemd-tmpfiles[1205]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 00:31:35.734332 systemd-tmpfiles[1205]: ACLs are not supported, ignoring. Sep 9 00:31:35.734528 systemd-tmpfiles[1205]: ACLs are not supported, ignoring. Sep 9 00:31:35.858453 systemd-tmpfiles[1205]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 00:31:35.858676 systemd-tmpfiles[1205]: Skipping /boot Sep 9 00:31:35.953475 systemd-tmpfiles[1205]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 00:31:35.953501 systemd-tmpfiles[1205]: Skipping /boot Sep 9 00:31:36.033345 zram_generator::config[1239]: No configuration found. Sep 9 00:31:36.662671 ldconfig[1166]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 00:31:36.708635 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 9 00:31:36.807078 systemd[1]: Reloading finished in 1144 ms. Sep 9 00:31:36.873970 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 00:31:36.909567 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 00:31:37.054252 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 9 00:31:37.072202 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 00:31:37.093242 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 00:31:37.131300 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 00:31:37.138832 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 00:31:37.175831 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 00:31:37.177548 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 00:31:37.184673 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 00:31:37.188769 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 00:31:37.192221 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 00:31:37.193884 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 00:31:37.200284 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 00:31:37.204556 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 00:31:37.257277 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 00:31:37.257646 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 00:31:37.284863 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 00:31:37.286408 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 00:31:37.286624 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 00:31:37.287738 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 00:31:37.287996 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 00:31:37.300238 systemd[1]: Finished ensure-sysext.service. Sep 9 00:31:37.305680 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 00:31:37.305943 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 00:31:37.313622 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 00:31:37.314028 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 00:31:37.321849 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 00:31:37.322153 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 00:31:37.350036 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 00:31:37.353796 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 00:31:37.353969 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 00:31:37.372731 augenrules[1304]: No rules Sep 9 00:31:37.388070 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 9 00:31:37.391304 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 00:31:37.394639 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 9 00:31:37.397245 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 00:31:37.431251 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 00:31:37.447413 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 00:31:37.466750 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 00:31:37.515139 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 00:31:37.523132 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 9 00:31:37.523908 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 00:31:37.548680 systemd-udevd[1312]: Using default interface naming scheme 'v255'. Sep 9 00:31:37.612459 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 00:31:37.646491 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 00:31:37.651928 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 9 00:31:37.673956 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 00:31:37.677046 systemd-resolved[1282]: Positive Trust Anchors: Sep 9 00:31:37.677060 systemd-resolved[1282]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 00:31:37.678184 systemd-resolved[1282]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 00:31:37.692614 systemd-resolved[1282]: Defaulting to hostname 'linux'. Sep 9 00:31:37.695059 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 00:31:37.697778 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 00:31:37.808378 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1332) Sep 9 00:31:37.805336 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 9 00:31:37.871795 systemd-networkd[1325]: lo: Link UP Sep 9 00:31:37.875603 systemd-networkd[1325]: lo: Gained carrier Sep 9 00:31:37.881887 systemd-networkd[1325]: Enumeration completed Sep 9 00:31:37.883102 systemd-networkd[1325]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:31:37.883289 systemd-networkd[1325]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 00:31:37.885556 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 00:31:37.887215 systemd-networkd[1325]: eth0: Link UP Sep 9 00:31:37.887224 systemd-networkd[1325]: eth0: Gained carrier Sep 9 00:31:37.887252 systemd-networkd[1325]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:31:37.891400 systemd[1]: Reached target network.target - Network. Sep 9 00:31:37.906846 systemd-networkd[1325]: eth0: DHCPv4 address 10.0.0.110/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 00:31:37.907007 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 9 00:31:37.907737 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Sep 9 00:31:37.908593 systemd-timesyncd[1303]: Network configuration changed, trying to establish connection. Sep 9 00:31:37.913889 kernel: ACPI: button: Power Button [PWRF] Sep 9 00:31:39.345294 systemd-timesyncd[1303]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 9 00:31:39.345551 systemd-timesyncd[1303]: Initial clock synchronization to Tue 2025-09-09 00:31:39.345074 UTC. Sep 9 00:31:39.345717 systemd-resolved[1282]: Clock change detected. Flushing caches. Sep 9 00:31:39.378806 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 00:31:39.390403 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 00:31:39.421746 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 9 00:31:39.445866 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 00:31:39.519452 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Sep 9 00:31:39.521297 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 9 00:31:39.527531 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Sep 9 00:31:39.529259 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 9 00:31:39.526950 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:31:39.556497 kernel: mousedev: PS/2 mouse device common for all mice Sep 9 00:31:39.823370 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:31:40.112528 kernel: kvm_amd: TSC scaling supported Sep 9 00:31:40.112640 kernel: kvm_amd: Nested Virtualization enabled Sep 9 00:31:40.112656 kernel: kvm_amd: Nested Paging enabled Sep 9 00:31:40.113057 kernel: kvm_amd: LBR virtualization supported Sep 9 00:31:40.114355 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 9 00:31:40.114432 kernel: kvm_amd: Virtual GIF supported Sep 9 00:31:40.219737 kernel: EDAC MC: Ver: 3.0.0 Sep 9 00:31:40.255440 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 9 00:31:40.272657 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 9 00:31:40.308651 lvm[1367]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 9 00:31:40.372013 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 9 00:31:40.379027 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 00:31:40.381291 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 00:31:40.385875 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 00:31:40.388662 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 00:31:40.390828 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 00:31:40.394212 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 00:31:40.397331 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 00:31:40.399767 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 9 00:31:40.399833 systemd[1]: Reached target paths.target - Path Units. Sep 9 00:31:40.401305 systemd[1]: Reached target timers.target - Timer Units. Sep 9 00:31:40.407454 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 00:31:40.413493 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 00:31:40.432525 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 00:31:40.444174 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 9 00:31:40.448302 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 00:31:40.450690 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 00:31:40.452354 systemd[1]: Reached target basic.target - Basic System. Sep 9 00:31:40.453982 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 9 00:31:40.456611 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 9 00:31:40.460881 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 00:31:40.469407 lvm[1371]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 9 00:31:40.474449 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 00:31:40.482056 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 00:31:40.511551 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 00:31:40.515313 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 9 00:31:40.522845 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 00:31:40.528261 jq[1374]: false Sep 9 00:31:40.543171 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 00:31:40.547713 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 00:31:40.567596 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 00:31:40.569629 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 9 00:31:40.570472 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 00:31:40.577761 extend-filesystems[1375]: Found loop2 Sep 9 00:31:40.579128 extend-filesystems[1375]: Found loop3 Sep 9 00:31:40.580037 extend-filesystems[1375]: Found sr0 Sep 9 00:31:40.580037 extend-filesystems[1375]: Found vda Sep 9 00:31:40.580037 extend-filesystems[1375]: Found vda1 Sep 9 00:31:40.580037 extend-filesystems[1375]: Found vda2 Sep 9 00:31:40.580037 extend-filesystems[1375]: Found vda3 Sep 9 00:31:40.591295 extend-filesystems[1375]: Found usr Sep 9 00:31:40.591295 extend-filesystems[1375]: Found vda4 Sep 9 00:31:40.591295 extend-filesystems[1375]: Found vda6 Sep 9 00:31:40.591295 extend-filesystems[1375]: Found vda7 Sep 9 00:31:40.591295 extend-filesystems[1375]: Found vda9 Sep 9 00:31:40.591295 extend-filesystems[1375]: Checking size of /dev/vda9 Sep 9 00:31:40.585241 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 00:31:40.587111 dbus-daemon[1373]: [system] SELinux support is enabled Sep 9 00:31:40.603785 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 00:31:40.610563 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 00:31:40.616181 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 9 00:31:40.643985 systemd-networkd[1325]: eth0: Gained IPv6LL Sep 9 00:31:40.657530 update_engine[1383]: I20250909 00:31:40.656136 1383 main.cc:92] Flatcar Update Engine starting Sep 9 00:31:40.657807 extend-filesystems[1375]: Old size kept for /dev/vda9 Sep 9 00:31:40.644195 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 00:31:40.663773 jq[1389]: true Sep 9 00:31:40.644720 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 00:31:40.645256 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 00:31:40.645809 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 00:31:40.649945 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 00:31:40.650286 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 00:31:40.657413 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 00:31:40.660533 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 00:31:40.675870 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 9 00:31:40.689481 update_engine[1383]: I20250909 00:31:40.686321 1383 update_check_scheduler.cc:74] Next update check in 8m51s Sep 9 00:31:40.690087 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1337) Sep 9 00:31:40.708574 (ntainerd)[1396]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 00:31:40.716014 jq[1399]: true Sep 9 00:31:40.879663 systemd-logind[1381]: Watching system buttons on /dev/input/event1 (Power Button) Sep 9 00:31:40.879700 systemd-logind[1381]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 9 00:31:40.882502 systemd-logind[1381]: New seat seat0. Sep 9 00:31:40.885169 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 00:31:40.894723 systemd[1]: Started update-engine.service - Update Engine. Sep 9 00:31:40.898765 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 00:31:40.921733 sshd_keygen[1393]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 9 00:31:40.919444 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 9 00:31:40.930171 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 00:31:40.934770 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 9 00:31:40.935100 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 00:31:40.940279 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 9 00:31:40.940591 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 00:31:40.949930 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 00:31:40.982408 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 00:31:40.995699 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 00:31:41.042131 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 9 00:31:41.042511 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 9 00:31:41.080710 bash[1425]: Updated "/home/core/.ssh/authorized_keys" Sep 9 00:31:41.092887 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 00:31:41.108092 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 00:31:41.112842 locksmithd[1431]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 00:31:41.114103 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 00:31:41.114439 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 00:31:41.167578 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 9 00:31:41.169798 (-wrapper)[1457]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 9 00:31:41.170580 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 9 00:31:41.171228 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 9 00:31:41.173708 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 00:31:41.296868 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 00:31:41.342926 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 00:31:41.418673 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 9 00:31:41.420741 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 00:31:42.138399 containerd[1396]: time="2025-09-09T00:31:42.138227635Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Sep 9 00:31:42.189859 containerd[1396]: time="2025-09-09T00:31:42.186622710Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:31:42.192373 containerd[1396]: time="2025-09-09T00:31:42.192272327Z" 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.104-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:31:42.192373 containerd[1396]: time="2025-09-09T00:31:42.192352598Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 9 00:31:42.192472 containerd[1396]: time="2025-09-09T00:31:42.192385680Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 9 00:31:42.192752 containerd[1396]: time="2025-09-09T00:31:42.192703005Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 9 00:31:42.192800 containerd[1396]: time="2025-09-09T00:31:42.192753420Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 9 00:31:42.192925 containerd[1396]: time="2025-09-09T00:31:42.192879436Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:31:42.192925 containerd[1396]: time="2025-09-09T00:31:42.192909442Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:31:42.193298 containerd[1396]: time="2025-09-09T00:31:42.193241535Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:31:42.193298 containerd[1396]: time="2025-09-09T00:31:42.193275108Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 9 00:31:42.193374 containerd[1396]: time="2025-09-09T00:31:42.193313811Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:31:42.193374 containerd[1396]: time="2025-09-09T00:31:42.193330342Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 9 00:31:42.197047 containerd[1396]: time="2025-09-09T00:31:42.195589068Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:31:42.200725 containerd[1396]: time="2025-09-09T00:31:42.197428619Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:31:42.200725 containerd[1396]: time="2025-09-09T00:31:42.199227243Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:31:42.201039 containerd[1396]: time="2025-09-09T00:31:42.200928504Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 9 00:31:42.202482 containerd[1396]: time="2025-09-09T00:31:42.202229193Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 9 00:31:42.202482 containerd[1396]: time="2025-09-09T00:31:42.202360770Z" level=info msg="metadata content store policy set" policy=shared Sep 9 00:31:42.223886 containerd[1396]: time="2025-09-09T00:31:42.223526070Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 9 00:31:42.223886 containerd[1396]: time="2025-09-09T00:31:42.223635906Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 9 00:31:42.223886 containerd[1396]: time="2025-09-09T00:31:42.223660883Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 9 00:31:42.223886 containerd[1396]: time="2025-09-09T00:31:42.223682193Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 9 00:31:42.223886 containerd[1396]: time="2025-09-09T00:31:42.223707310Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 9 00:31:42.224177 containerd[1396]: time="2025-09-09T00:31:42.224129803Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224461325Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224640801Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224661270Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224678672Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224697578Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224714309Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224730610Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224748573Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224767288Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224786725Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224802955Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224819476Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224861175Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.226983 containerd[1396]: time="2025-09-09T00:31:42.224880170Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.224899557Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.224933200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.224950412Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.224967173Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.224980709Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225022207Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225037335Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225053024Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225069756Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225083862Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225099832Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225125961Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225156098Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225172088Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.227889 containerd[1396]: time="2025-09-09T00:31:42.225191003Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225252078Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225276874Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225301340Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225318903Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225332769Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225350252Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225364268Z" level=info msg="NRI interface is disabled by configuration." Sep 9 00:31:42.228349 containerd[1396]: time="2025-09-09T00:31:42.225378836Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 9 00:31:42.228583 containerd[1396]: time="2025-09-09T00:31:42.225740153Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Sep 9 00:31:42.228583 containerd[1396]: time="2025-09-09T00:31:42.225833899Z" level=info msg="Connect containerd service" Sep 9 00:31:42.228583 containerd[1396]: time="2025-09-09T00:31:42.225894332Z" level=info msg="using legacy CRI server" Sep 9 00:31:42.228583 containerd[1396]: time="2025-09-09T00:31:42.225904231Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 00:31:42.228583 containerd[1396]: time="2025-09-09T00:31:42.226108344Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 9 00:31:42.235364 containerd[1396]: time="2025-09-09T00:31:42.231776626Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 9 00:31:42.235364 containerd[1396]: time="2025-09-09T00:31:42.234659063Z" level=info msg="Start subscribing containerd event" Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.237094902Z" level=info msg="Start recovering state" Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.237251425Z" level=info msg="Start event monitor" Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.237271974Z" level=info msg="Start snapshots syncer" Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.237297862Z" level=info msg="Start cni network conf syncer for default" Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.237309714Z" level=info msg="Start streaming server" Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.238340127Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.238428032Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 00:31:42.243662 containerd[1396]: time="2025-09-09T00:31:42.239016025Z" level=info msg="containerd successfully booted in 0.109478s" Sep 9 00:31:42.238657 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 00:31:42.339756 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 9 00:31:42.345502 (dockerd)[1479]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 9 00:31:45.104275 dockerd[1479]: time="2025-09-09T00:31:45.093281050Z" level=info msg="Starting up" Sep 9 00:31:45.369457 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport783086977-merged.mount: Deactivated successfully. Sep 9 00:31:45.481383 systemd[1]: var-lib-docker-metacopy\x2dcheck3585177957-merged.mount: Deactivated successfully. Sep 9 00:31:45.594383 dockerd[1479]: time="2025-09-09T00:31:45.593385533Z" level=info msg="Loading containers: start." Sep 9 00:31:46.186383 kernel: Initializing XFRM netlink socket Sep 9 00:31:46.529720 systemd-networkd[1325]: docker0: Link UP Sep 9 00:31:46.588091 dockerd[1479]: time="2025-09-09T00:31:46.587087574Z" level=info msg="Loading containers: done." Sep 9 00:31:46.690919 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1119816305-merged.mount: Deactivated successfully. Sep 9 00:31:46.703932 dockerd[1479]: time="2025-09-09T00:31:46.703743838Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 9 00:31:46.704369 dockerd[1479]: time="2025-09-09T00:31:46.704329316Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 Sep 9 00:31:46.705332 dockerd[1479]: time="2025-09-09T00:31:46.705053655Z" level=info msg="Daemon has completed initialization" Sep 9 00:31:46.842489 dockerd[1479]: time="2025-09-09T00:31:46.839912923Z" level=info msg="API listen on /run/docker.sock" Sep 9 00:31:46.840307 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 9 00:31:46.852238 etcd-wrapper[1462]: Error response from daemon: No such container: etcd-member Sep 9 00:31:46.997355 etcd-wrapper[1613]: Error response from daemon: No such container: etcd-member Sep 9 00:31:47.081768 etcd-wrapper[1631]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Sep 9 00:31:48.390418 etcd-wrapper[1631]: v3.5.16: Pulling from coreos/etcd Sep 9 00:31:48.859217 etcd-wrapper[1631]: 804c8aba2cc6: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 2ae710cd8bfe: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: d462aa345367: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 0f8b424aa0b9: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: d557676654e5: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: c8022d07192e: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: d858cbc252ad: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 1069fc2daed1: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: b40161cd83fc: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 5318d93a3a65: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 307c1adadb60: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: fbb01d9e9dc9: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: fbfea02ac3cf: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 8c26e4bf18e2: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 1e59a65f8816: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: ffbd4ca5f0bd: Pulling fs layer Sep 9 00:31:48.859217 etcd-wrapper[1631]: 0f8b424aa0b9: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: d557676654e5: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: c8022d07192e: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: d858cbc252ad: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: 1069fc2daed1: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: b40161cd83fc: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: 5318d93a3a65: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: 307c1adadb60: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: fbb01d9e9dc9: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: fbfea02ac3cf: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: 8c26e4bf18e2: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: 1e59a65f8816: Waiting Sep 9 00:31:48.859217 etcd-wrapper[1631]: ffbd4ca5f0bd: Waiting Sep 9 00:31:49.155950 etcd-wrapper[1631]: 2ae710cd8bfe: Download complete Sep 9 00:31:49.185647 etcd-wrapper[1631]: 804c8aba2cc6: Verifying Checksum Sep 9 00:31:49.185647 etcd-wrapper[1631]: 804c8aba2cc6: Download complete Sep 9 00:31:49.302157 etcd-wrapper[1631]: d462aa345367: Download complete Sep 9 00:31:49.449184 etcd-wrapper[1631]: 0f8b424aa0b9: Verifying Checksum Sep 9 00:31:49.449184 etcd-wrapper[1631]: 0f8b424aa0b9: Download complete Sep 9 00:31:49.569730 etcd-wrapper[1631]: c8022d07192e: Verifying Checksum Sep 9 00:31:49.569730 etcd-wrapper[1631]: c8022d07192e: Download complete Sep 9 00:31:49.604748 etcd-wrapper[1631]: d557676654e5: Verifying Checksum Sep 9 00:31:49.604748 etcd-wrapper[1631]: d557676654e5: Download complete Sep 9 00:31:49.657390 etcd-wrapper[1631]: 804c8aba2cc6: Pull complete Sep 9 00:31:49.694485 systemd[1]: var-lib-docker-overlay2-de53a28e2a12c7bd9a0d0ec1b17535e0f626792ecec8448beaf79cf29b46b75d-merged.mount: Deactivated successfully. Sep 9 00:31:49.742754 etcd-wrapper[1631]: d858cbc252ad: Download complete Sep 9 00:31:49.764480 etcd-wrapper[1631]: 2ae710cd8bfe: Pull complete Sep 9 00:31:49.876600 etcd-wrapper[1631]: b40161cd83fc: Verifying Checksum Sep 9 00:31:49.876600 etcd-wrapper[1631]: b40161cd83fc: Download complete Sep 9 00:31:49.925165 etcd-wrapper[1631]: 1069fc2daed1: Download complete Sep 9 00:31:50.022214 etcd-wrapper[1631]: 5318d93a3a65: Verifying Checksum Sep 9 00:31:50.022214 etcd-wrapper[1631]: 5318d93a3a65: Download complete Sep 9 00:31:50.248095 etcd-wrapper[1631]: 307c1adadb60: Verifying Checksum Sep 9 00:31:50.248095 etcd-wrapper[1631]: 307c1adadb60: Download complete Sep 9 00:31:50.318156 etcd-wrapper[1631]: fbb01d9e9dc9: Verifying Checksum Sep 9 00:31:50.318156 etcd-wrapper[1631]: fbb01d9e9dc9: Download complete Sep 9 00:31:50.414748 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 00:31:50.451151 systemd[1]: Started sshd@0-10.0.0.110:22-10.0.0.1:55370.service - OpenSSH per-connection server daemon (10.0.0.1:55370). Sep 9 00:31:50.479959 etcd-wrapper[1631]: fbfea02ac3cf: Verifying Checksum Sep 9 00:31:50.479959 etcd-wrapper[1631]: fbfea02ac3cf: Download complete Sep 9 00:31:50.624213 etcd-wrapper[1631]: 1e59a65f8816: Verifying Checksum Sep 9 00:31:50.624213 etcd-wrapper[1631]: 1e59a65f8816: Download complete Sep 9 00:31:50.640102 etcd-wrapper[1631]: 8c26e4bf18e2: Verifying Checksum Sep 9 00:31:50.640102 etcd-wrapper[1631]: 8c26e4bf18e2: Download complete Sep 9 00:31:50.662800 sshd[1659]: Accepted publickey for core from 10.0.0.1 port 55370 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:31:50.670800 sshd[1659]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:50.698357 systemd-logind[1381]: New session 1 of user core. Sep 9 00:31:50.698642 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 00:31:50.711935 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 00:31:50.757890 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 00:31:50.778421 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 00:31:50.789549 (systemd)[1663]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 00:31:50.928073 etcd-wrapper[1631]: ffbd4ca5f0bd: Download complete Sep 9 00:31:51.067595 systemd[1663]: Queued start job for default target default.target. Sep 9 00:31:51.082664 systemd[1663]: Created slice app.slice - User Application Slice. Sep 9 00:31:51.083439 systemd[1663]: Reached target paths.target - Paths. Sep 9 00:31:51.084132 systemd[1663]: Reached target timers.target - Timers. Sep 9 00:31:51.087199 systemd[1663]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 00:31:51.125922 systemd[1663]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 00:31:51.130365 systemd[1663]: Reached target sockets.target - Sockets. Sep 9 00:31:51.130404 systemd[1663]: Reached target basic.target - Basic System. Sep 9 00:31:51.130483 systemd[1663]: Reached target default.target - Main User Target. Sep 9 00:31:51.130537 systemd[1663]: Startup finished in 328ms. Sep 9 00:31:51.132175 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 00:31:51.143410 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 00:31:51.235290 systemd[1]: Started sshd@1-10.0.0.110:22-10.0.0.1:55372.service - OpenSSH per-connection server daemon (10.0.0.1:55372). Sep 9 00:31:51.456818 sshd[1675]: Accepted publickey for core from 10.0.0.1 port 55372 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:31:51.468589 sshd[1675]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:51.485167 systemd-logind[1381]: New session 2 of user core. Sep 9 00:31:51.506342 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 00:31:51.605187 sshd[1675]: pam_unix(sshd:session): session closed for user core Sep 9 00:31:51.617505 systemd[1]: sshd@1-10.0.0.110:22-10.0.0.1:55372.service: Deactivated successfully. Sep 9 00:31:51.620634 systemd[1]: session-2.scope: Deactivated successfully. Sep 9 00:31:51.623779 systemd-logind[1381]: Session 2 logged out. Waiting for processes to exit. Sep 9 00:31:51.635599 systemd[1]: Started sshd@2-10.0.0.110:22-10.0.0.1:55386.service - OpenSSH per-connection server daemon (10.0.0.1:55386). Sep 9 00:31:51.636891 systemd-logind[1381]: Removed session 2. Sep 9 00:31:51.701432 systemd[1]: var-lib-docker-overlay2-4154ede1b1b78f83736dc673255cc40a4fa7771ff76a12e0310d09259deeab10-merged.mount: Deactivated successfully. Sep 9 00:31:51.741444 sshd[1682]: Accepted publickey for core from 10.0.0.1 port 55386 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:31:51.749708 sshd[1682]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:51.782162 systemd-logind[1381]: New session 3 of user core. Sep 9 00:31:51.796433 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 00:31:52.073200 sshd[1682]: pam_unix(sshd:session): session closed for user core Sep 9 00:31:52.091740 systemd[1]: sshd@2-10.0.0.110:22-10.0.0.1:55386.service: Deactivated successfully. Sep 9 00:31:52.105271 systemd[1]: session-3.scope: Deactivated successfully. Sep 9 00:31:52.110155 systemd-logind[1381]: Session 3 logged out. Waiting for processes to exit. Sep 9 00:31:52.116740 systemd-logind[1381]: Removed session 3. Sep 9 00:31:52.362636 etcd-wrapper[1631]: d462aa345367: Pull complete Sep 9 00:31:52.423816 systemd[1]: var-lib-docker-overlay2-1ac9f655d9e4cfa842e7189792bbef9e797bddfad61db08b9aefe3639a1fbd51-merged.mount: Deactivated successfully. Sep 9 00:31:53.045031 etcd-wrapper[1631]: 0f8b424aa0b9: Pull complete Sep 9 00:31:53.112149 systemd[1]: var-lib-docker-overlay2-03725af878ec231cf40b6fd0c0cf7041566a6c851a16ed56c6fa829b81047bec-merged.mount: Deactivated successfully. Sep 9 00:31:53.164256 etcd-wrapper[1631]: d557676654e5: Pull complete Sep 9 00:31:53.386172 etcd-wrapper[1631]: c8022d07192e: Pull complete Sep 9 00:31:53.403770 systemd[1]: var-lib-docker-overlay2-6f8aa5a8795c1bec3fe740ebcdd38783275cf83df01d40ebe1d40ef24516f131-merged.mount: Deactivated successfully. Sep 9 00:31:53.725706 systemd[1]: var-lib-docker-overlay2-4d0f6e53c514eb3a9ee8e35ab9ae32bc208136508e56f2203d03d6761f4518ea-merged.mount: Deactivated successfully. Sep 9 00:31:54.395583 etcd-wrapper[1631]: d858cbc252ad: Pull complete Sep 9 00:31:54.487282 systemd[1]: var-lib-docker-overlay2-dd9dfbe1ae5456ff3714f0152076d6d4c74be015128c9239f71da82d5124ae01-merged.mount: Deactivated successfully. Sep 9 00:31:54.705820 etcd-wrapper[1631]: 1069fc2daed1: Pull complete Sep 9 00:31:54.780665 systemd[1]: var-lib-docker-overlay2-b7a3c879bfe36e0141ac0d74b0488b65b3b4035534eb2b84b4cc0efa8d7b48c9-merged.mount: Deactivated successfully. Sep 9 00:31:54.826173 etcd-wrapper[1631]: b40161cd83fc: Pull complete Sep 9 00:31:54.911040 etcd-wrapper[1631]: 5318d93a3a65: Pull complete Sep 9 00:31:55.025909 etcd-wrapper[1631]: 307c1adadb60: Pull complete Sep 9 00:31:55.469424 systemd[1]: var-lib-docker-overlay2-aceb40012a339c21fef21aa0eebfdd27af28920b675966d9ed223539e3dda79f-merged.mount: Deactivated successfully. Sep 9 00:31:56.060710 systemd[1]: var-lib-docker-overlay2-6afbfecc699e13f1367ce42157e1e756bcdbbf840d0f36b604e8406fbd31e010-merged.mount: Deactivated successfully. Sep 9 00:31:56.223961 etcd-wrapper[1631]: fbb01d9e9dc9: Pull complete Sep 9 00:31:57.014228 systemd[1]: var-lib-docker-overlay2-42be4a404c3e3db3696ba53532dbe323b934929d6f2a4e29af61d5ce65134ad3-merged.mount: Deactivated successfully. Sep 9 00:31:57.219864 etcd-wrapper[1631]: fbfea02ac3cf: Pull complete Sep 9 00:31:57.906305 systemd[1]: var-lib-docker-overlay2-29f472d3590b0b93dd39830c7e21b17172fe07188dd6c73caeee05dd1fb74d40-merged.mount: Deactivated successfully. Sep 9 00:31:58.368495 etcd-wrapper[1631]: 8c26e4bf18e2: Pull complete Sep 9 00:31:58.532806 systemd[1]: var-lib-docker-overlay2-3a14341fa349ab32d3bce87acba77b5323706aab8f9be125de46a59a1833a89d-merged.mount: Deactivated successfully. Sep 9 00:31:58.561357 etcd-wrapper[1631]: 1e59a65f8816: Pull complete Sep 9 00:31:58.597280 systemd[1]: var-lib-docker-overlay2-2004d0b353f1c38542752cded16cbe75bde2055e2d0545357abb01a0ba3a2f9e-merged.mount: Deactivated successfully. Sep 9 00:31:58.629555 etcd-wrapper[1631]: ffbd4ca5f0bd: Pull complete Sep 9 00:31:58.669619 etcd-wrapper[1631]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Sep 9 00:31:58.684506 etcd-wrapper[1631]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Sep 9 00:31:59.510878 containerd[1396]: time="2025-09-09T00:31:59.509399268Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 9 00:31:59.510878 containerd[1396]: time="2025-09-09T00:31:59.510707021Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 9 00:31:59.510878 containerd[1396]: time="2025-09-09T00:31:59.510727188Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:59.511622 containerd[1396]: time="2025-09-09T00:31:59.510900143Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:59.588828 systemd[1]: Started docker-83993c3feea8a6f3093da13f31198fcd9aa08621688bbf7f2516cebc0729fe87.scope - libcontainer container 83993c3feea8a6f3093da13f31198fcd9aa08621688bbf7f2516cebc0729fe87. Sep 9 00:31:59.650214 systemd-resolved[1282]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:31:59.748762Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:31:59.749460Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"60966e25262345e2bfdf9cf7cb5f1550"} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:31:59.749969Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:31:59.750050Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:31:59.750060Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:31:59.750067Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:31:59.750151Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:31:59.750188Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.0.0.110:2379"]} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:31:59.750308Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 9 00:31:59.750591 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:31:59.750324Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Sep 9 00:31:59.752625 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:31:59.751102Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 9 00:31:59.752625 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:31:59.751312Z","caller":"embed/etcd.go:311","msg":"starting an etcd server","etcd-version":"3.5.16","git-sha":"f20bbad","go-version":"go1.22.7","go-os":"linux","go-arch":"amd64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"60966e25262345e2bfdf9cf7cb5f1550","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.110:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"60966e25262345e2bfdf9cf7cb5f1550=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Sep 9 00:32:00.403752 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.402858Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"651.013836ms"} Sep 9 00:32:00.748166 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.746707Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Sep 9 00:32:00.748364 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.746921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Sep 9 00:32:00.748364 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.747046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Sep 9 00:32:00.748364 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.747071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Sep 9 00:32:00.748364 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.747103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Sep 9 00:32:00.748364 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.747171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 9 00:32:00.844724 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:32:00.844177Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Sep 9 00:32:00.856148 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.855292Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Sep 9 00:32:00.859818 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.859271Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Sep 9 00:32:00.864447 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.862742Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Sep 9 00:32:00.864447 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.863224Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 9 00:32:00.864755 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.863579Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Sep 9 00:32:00.864755 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.864248Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Sep 9 00:32:00.864755 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.864329Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Sep 9 00:32:00.864976 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.863665Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Sep 9 00:32:00.868217 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.867758Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Sep 9 00:32:00.868674 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.867804Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.110:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 9 00:32:00.868674 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.867881Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Sep 9 00:32:00.870464 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.869965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 9 00:32:00.870464 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:00.870167Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} Sep 9 00:32:01.150711 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.149824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Sep 9 00:32:01.150711 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.149908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Sep 9 00:32:01.150711 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.149966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Sep 9 00:32:01.150711 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.149987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Sep 9 00:32:01.150711 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.150017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Sep 9 00:32:01.150711 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.150029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Sep 9 00:32:01.150711 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.150040Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Sep 9 00:32:01.153596 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.153053Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Sep 9 00:32:01.156153 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.154301Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:60966e25262345e2bfdf9cf7cb5f1550 ClientURLs:[http://10.0.0.110:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Sep 9 00:32:01.156153 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.154357Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Sep 9 00:32:01.157982 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 9 00:32:01.158414 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 00:32:01.159030 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.155265Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 9 00:32:01.159030 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.155822Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Sep 9 00:32:01.159030 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.155814Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Sep 9 00:32:01.159030 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.156566Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Sep 9 00:32:01.159030 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.156861Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Sep 9 00:32:01.159030 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.156949Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 9 00:32:01.159030 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:01.156987Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Sep 9 00:32:01.159837 systemd[1]: Startup finished in 2.550s (kernel) + 8.972s (initrd) + 27.989s (userspace) = 39.511s. Sep 9 00:32:01.943524 systemd[1]: Started sshd@3-10.0.0.110:22-10.0.0.1:33180.service - OpenSSH per-connection server daemon (10.0.0.1:33180). Sep 9 00:32:01.983634 sshd[1801]: Accepted publickey for core from 10.0.0.1 port 33180 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:32:01.985740 sshd[1801]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:32:01.992532 systemd-logind[1381]: New session 4 of user core. Sep 9 00:32:02.002451 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 9 00:32:02.065295 sshd[1801]: pam_unix(sshd:session): session closed for user core Sep 9 00:32:02.079653 systemd[1]: sshd@3-10.0.0.110:22-10.0.0.1:33180.service: Deactivated successfully. Sep 9 00:32:02.084198 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 00:32:02.086810 systemd-logind[1381]: Session 4 logged out. Waiting for processes to exit. Sep 9 00:32:02.103884 systemd[1]: Started sshd@4-10.0.0.110:22-10.0.0.1:33194.service - OpenSSH per-connection server daemon (10.0.0.1:33194). Sep 9 00:32:02.105922 systemd-logind[1381]: Removed session 4. Sep 9 00:32:02.145790 sshd[1808]: Accepted publickey for core from 10.0.0.1 port 33194 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:32:02.148484 sshd[1808]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:32:02.159412 systemd-logind[1381]: New session 5 of user core. Sep 9 00:32:02.176062 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 00:32:02.237714 sshd[1808]: pam_unix(sshd:session): session closed for user core Sep 9 00:32:02.252149 systemd[1]: sshd@4-10.0.0.110:22-10.0.0.1:33194.service: Deactivated successfully. Sep 9 00:32:02.254983 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 00:32:02.257486 systemd-logind[1381]: Session 5 logged out. Waiting for processes to exit. Sep 9 00:32:02.269693 systemd[1]: Started sshd@5-10.0.0.110:22-10.0.0.1:33202.service - OpenSSH per-connection server daemon (10.0.0.1:33202). Sep 9 00:32:02.272481 systemd-logind[1381]: Removed session 5. Sep 9 00:32:02.307470 sshd[1815]: Accepted publickey for core from 10.0.0.1 port 33202 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:32:02.310851 sshd[1815]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:32:02.317729 systemd-logind[1381]: New session 6 of user core. Sep 9 00:32:02.331444 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 9 00:32:02.396755 sshd[1815]: pam_unix(sshd:session): session closed for user core Sep 9 00:32:02.420833 systemd[1]: sshd@5-10.0.0.110:22-10.0.0.1:33202.service: Deactivated successfully. Sep 9 00:32:02.425144 systemd[1]: session-6.scope: Deactivated successfully. Sep 9 00:32:02.429423 systemd-logind[1381]: Session 6 logged out. Waiting for processes to exit. Sep 9 00:32:02.448610 systemd[1]: Started sshd@6-10.0.0.110:22-10.0.0.1:33204.service - OpenSSH per-connection server daemon (10.0.0.1:33204). Sep 9 00:32:02.451040 systemd-logind[1381]: Removed session 6. Sep 9 00:32:02.485766 sshd[1822]: Accepted publickey for core from 10.0.0.1 port 33204 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:32:02.488320 sshd[1822]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:32:02.500269 systemd-logind[1381]: New session 7 of user core. Sep 9 00:32:02.510359 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 9 00:32:02.571333 sshd[1822]: pam_unix(sshd:session): session closed for user core Sep 9 00:32:02.580674 systemd[1]: sshd@6-10.0.0.110:22-10.0.0.1:33204.service: Deactivated successfully. Sep 9 00:32:02.585016 systemd[1]: session-7.scope: Deactivated successfully. Sep 9 00:32:02.585963 systemd-logind[1381]: Session 7 logged out. Waiting for processes to exit. Sep 9 00:32:02.599657 systemd[1]: Started sshd@7-10.0.0.110:22-10.0.0.1:33216.service - OpenSSH per-connection server daemon (10.0.0.1:33216). Sep 9 00:32:02.601630 systemd-logind[1381]: Removed session 7. Sep 9 00:32:02.638934 sshd[1831]: Accepted publickey for core from 10.0.0.1 port 33216 ssh2: RSA SHA256:KA9/SrLi0HJZnQ3nz9u7pIgg2ymhn74LV9fPMAvvX5M Sep 9 00:32:02.641484 sshd[1831]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:32:02.647410 systemd-logind[1381]: New session 8 of user core. Sep 9 00:32:02.656574 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 9 00:32:03.112318 sshd[1831]: pam_unix(sshd:session): session closed for user core Sep 9 00:32:03.117822 systemd[1]: sshd@7-10.0.0.110:22-10.0.0.1:33216.service: Deactivated successfully. Sep 9 00:32:03.121380 systemd[1]: session-8.scope: Deactivated successfully. Sep 9 00:32:03.122806 systemd-logind[1381]: Session 8 logged out. Waiting for processes to exit. Sep 9 00:32:03.124448 systemd-logind[1381]: Removed session 8. Sep 9 00:32:25.671294 update_engine[1383]: I20250909 00:32:25.671172 1383 update_attempter.cc:509] Updating boot flags... Sep 9 00:32:25.718025 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1850) Sep 9 00:32:25.757031 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1850) Sep 9 00:32:25.791040 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1850) Sep 9 00:32:56.111040 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:56.110536Z","caller":"traceutil/trace.go:171","msg":"trace[470055553] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"111.119316ms","start":"2025-09-09T00:32:55.999395Z","end":"2025-09-09T00:32:56.110515Z","steps":["trace[470055553] 'process raft request' (duration: 109.490604ms)"],"step_count":1} Sep 9 00:32:57.499775 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:32:57.499138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.243954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" limit:1 ","response":"range_response_count:0 size:5"} Sep 9 00:32:57.499775 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:57.499272Z","caller":"traceutil/trace.go:171","msg":"trace[1835833435] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:0; response_revision:181; }","duration":"339.433437ms","start":"2025-09-09T00:32:57.159821Z","end":"2025-09-09T00:32:57.499254Z","steps":["trace[1835833435] 'range keys from in-memory index tree' (duration: 339.198668ms)"],"step_count":1} Sep 9 00:32:57.499775 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:32:57.499322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:32:57.159809Z","time spent":"339.497788ms","remote":"10.0.0.111:46352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":29,"request content":"key:\"/registry/clusterrolebindings/system:monitoring\" limit:1 "} Sep 9 00:32:59.109099 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:32:59.108559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.853833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 9 00:32:59.109099 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:59.108725Z","caller":"traceutil/trace.go:171","msg":"trace[1760770855] transaction","detail":"{read_only:false; number_of_response:0; response_revision:276; }","duration":"105.900356ms","start":"2025-09-09T00:32:59.002811Z","end":"2025-09-09T00:32:59.108711Z","steps":["trace[1760770855] 'process raft request' (duration: 105.878114ms)"],"step_count":1} Sep 9 00:32:59.109099 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:59.108755Z","caller":"traceutil/trace.go:171","msg":"trace[1962908955] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"181.600676ms","start":"2025-09-09T00:32:58.927139Z","end":"2025-09-09T00:32:59.108739Z","steps":["trace[1962908955] 'process raft request' (duration: 181.511028ms)"],"step_count":1} Sep 9 00:32:59.109099 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:59.108806Z","caller":"traceutil/trace.go:171","msg":"trace[758836237] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:280; }","duration":"144.69469ms","start":"2025-09-09T00:32:58.964102Z","end":"2025-09-09T00:32:59.108797Z","steps":["trace[758836237] 'read index received' (duration: 27.461µs)","trace[758836237] 'applied index is now lower than readState.Index' (duration: 144.666668ms)"],"step_count":2} Sep 9 00:32:59.109891 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:59.108832Z","caller":"traceutil/trace.go:171","msg":"trace[1225915274] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"183.119173ms","start":"2025-09-09T00:32:58.925701Z","end":"2025-09-09T00:32:59.108820Z","steps":["trace[1225915274] 'process raft request' (duration: 33.505029ms)","trace[1225915274] 'compare' (duration: 148.72893ms)"],"step_count":2} Sep 9 00:32:59.109891 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:32:59.108852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.752067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:5"} Sep 9 00:32:59.109891 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:32:59.108870Z","caller":"traceutil/trace.go:171","msg":"trace[1556499641] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:276; }","duration":"144.791231ms","start":"2025-09-09T00:32:58.964071Z","end":"2025-09-09T00:32:59.108862Z","steps":["trace[1556499641] 'agreement among raft nodes before linearized reading' (duration: 144.755845ms)"],"step_count":1} Sep 9 00:33:00.045625 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.045054Z","caller":"traceutil/trace.go:171","msg":"trace[912825326] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"171.734199ms","start":"2025-09-09T00:32:59.873302Z","end":"2025-09-09T00:33:00.045036Z","steps":["trace[912825326] 'read index received' (duration: 171.598886ms)","trace[912825326] 'applied index is now lower than readState.Index' (duration: 134.902µs)"],"step_count":2} Sep 9 00:33:00.045625 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.045128Z","caller":"traceutil/trace.go:171","msg":"trace[367341585] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"194.277272ms","start":"2025-09-09T00:32:59.850816Z","end":"2025-09-09T00:33:00.045093Z","steps":["trace[367341585] 'process raft request' (duration: 194.109037ms)"],"step_count":1} Sep 9 00:33:00.045625 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:00.045187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.863462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-controller-manager-localhost.186375f6190de4d3\" limit:1 ","response":"range_response_count:1 size:844"} Sep 9 00:33:00.045625 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.045214Z","caller":"traceutil/trace.go:171","msg":"trace[160650144] range","detail":"{range_begin:/registry/events/kube-system/kube-controller-manager-localhost.186375f6190de4d3; range_end:; response_count:1; response_revision:305; }","duration":"171.924415ms","start":"2025-09-09T00:32:59.873282Z","end":"2025-09-09T00:33:00.045206Z","steps":["trace[160650144] 'agreement among raft nodes before linearized reading' (duration: 171.840879ms)"],"step_count":1} Sep 9 00:33:00.058574 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.058306Z","caller":"traceutil/trace.go:171","msg":"trace[2002344736] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"183.942116ms","start":"2025-09-09T00:32:59.874353Z","end":"2025-09-09T00:33:00.058295Z","steps":["trace[2002344736] 'process raft request' (duration: 183.835647ms)"],"step_count":1} Sep 9 00:33:00.058574 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.058390Z","caller":"traceutil/trace.go:171","msg":"trace[1048770271] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"183.693572ms","start":"2025-09-09T00:32:59.874672Z","end":"2025-09-09T00:33:00.058366Z","steps":["trace[1048770271] 'process raft request' (duration: 183.583617ms)"],"step_count":1} Sep 9 00:33:00.058681 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:00.058418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.973295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-localhost\" limit:1 ","response":"range_response_count:1 size:4934"} Sep 9 00:33:00.058681 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.058533Z","caller":"traceutil/trace.go:171","msg":"trace[361802687] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-localhost; range_end:; response_count:1; response_revision:305; }","duration":"185.100612ms","start":"2025-09-09T00:32:59.873424Z","end":"2025-09-09T00:33:00.058525Z","steps":["trace[361802687] 'agreement among raft nodes before linearized reading' (duration: 184.940643ms)"],"step_count":1} Sep 9 00:33:00.387179 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:00.386686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.808009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" limit:1 ","response":"range_response_count:0 size:5"} Sep 9 00:33:00.387179 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.386761Z","caller":"traceutil/trace.go:171","msg":"trace[778076275] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:315; }","duration":"135.921501ms","start":"2025-09-09T00:33:00.250827Z","end":"2025-09-09T00:33:00.386748Z","steps":["trace[778076275] 'range keys from in-memory index tree' (duration: 135.746985ms)"],"step_count":1} Sep 9 00:33:00.387179 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:00.386776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.460015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" limit:1 ","response":"range_response_count:0 size:5"} Sep 9 00:33:00.387179 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:00.386859Z","caller":"traceutil/trace.go:171","msg":"trace[627293275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:315; }","duration":"109.594457ms","start":"2025-09-09T00:33:00.277250Z","end":"2025-09-09T00:33:00.386845Z","steps":["trace[627293275] 'range keys from in-memory index tree' (duration: 109.39831ms)"],"step_count":1} Sep 9 00:33:01.361898 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:01.361484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.918861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" limit:1 ","response":"range_response_count:0 size:5"} Sep 9 00:33:01.361898 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:01.361559Z","caller":"traceutil/trace.go:171","msg":"trace[570174266] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:332; }","duration":"162.032143ms","start":"2025-09-09T00:33:01.199513Z","end":"2025-09-09T00:33:01.361545Z","steps":["trace[570174266] 'range keys from in-memory index tree' (duration: 161.80044ms)"],"step_count":1} Sep 9 00:33:01.598808 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:01.598362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.238954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 9 00:33:01.598808 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:01.598548Z","caller":"traceutil/trace.go:171","msg":"trace[1612899929] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"195.892917ms","start":"2025-09-09T00:33:01.402628Z","end":"2025-09-09T00:33:01.598521Z","steps":["trace[1612899929] 'process raft request' (duration: 68.448368ms)","trace[1612899929] 'compare' (duration: 127.13482ms)"],"step_count":2} Sep 9 00:33:01.917355 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:01.916903Z","caller":"traceutil/trace.go:171","msg":"trace[1643696198] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"186.549161ms","start":"2025-09-09T00:33:01.730336Z","end":"2025-09-09T00:33:01.916886Z","steps":["trace[1643696198] 'read index received' (duration: 186.384934ms)","trace[1643696198] 'applied index is now lower than readState.Index' (duration: 163.696µs)"],"step_count":2} Sep 9 00:33:01.917355 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:01.916956Z","caller":"traceutil/trace.go:171","msg":"trace[1356352347] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"216.838807ms","start":"2025-09-09T00:33:01.700086Z","end":"2025-09-09T00:33:01.916925Z","steps":["trace[1356352347] 'process raft request' (duration: 216.700728ms)"],"step_count":1} Sep 9 00:33:01.917355 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:01.917041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.67702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-controller-manager-localhost.186375f6190de4d3\" limit:1 ","response":"range_response_count:1 size:844"} Sep 9 00:33:01.917666 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:01.917171Z","caller":"traceutil/trace.go:171","msg":"trace[952382515] range","detail":"{range_begin:/registry/events/kube-system/kube-controller-manager-localhost.186375f6190de4d3; range_end:; response_count:1; response_revision:337; }","duration":"186.835516ms","start":"2025-09-09T00:33:01.730325Z","end":"2025-09-09T00:33:01.917161Z","steps":["trace[952382515] 'agreement among raft nodes before linearized reading' (duration: 186.624571ms)"],"step_count":1} Sep 9 00:33:02.754660 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:02.754090Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.808167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" limit:1 ","response":"range_response_count:1 size:351"} Sep 9 00:33:02.754660 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:02.754168Z","caller":"traceutil/trace.go:171","msg":"trace[174422878] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:344; }","duration":"153.938008ms","start":"2025-09-09T00:33:02.600212Z","end":"2025-09-09T00:33:02.754150Z","steps":["trace[174422878] 'range keys from in-memory index tree' (duration: 153.713689ms)"],"step_count":1} Sep 9 00:33:03.709318 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:03.708888Z","caller":"traceutil/trace.go:171","msg":"trace[61765667] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"107.348459ms","start":"2025-09-09T00:33:03.601514Z","end":"2025-09-09T00:33:03.708862Z","steps":["trace[61765667] 'process raft request' (duration: 67.430199ms)","trace[61765667] 'compare' (duration: 39.700714ms)"],"step_count":2} Sep 9 00:33:03.873981 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:03.873624Z","caller":"traceutil/trace.go:171","msg":"trace[219453091] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"115.208913ms","start":"2025-09-09T00:33:03.758396Z","end":"2025-09-09T00:33:03.873605Z","steps":["trace[219453091] 'read index received' (duration: 67.919464ms)","trace[219453091] 'applied index is now lower than readState.Index' (duration: 47.288768ms)"],"step_count":2} Sep 9 00:33:03.873981 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:03.873724Z","caller":"traceutil/trace.go:171","msg":"trace[2011483679] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"161.981207ms","start":"2025-09-09T00:33:03.711730Z","end":"2025-09-09T00:33:03.873711Z","steps":["trace[2011483679] 'process raft request' (duration: 114.573967ms)","trace[2011483679] 'compare' (duration: 47.21026ms)"],"step_count":2} Sep 9 00:33:03.873981 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:03.873736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.326554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-localhost.186375f61906f7e8\" limit:1 ","response":"range_response_count:1 size:817"} Sep 9 00:33:03.873981 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:03.873765Z","caller":"traceutil/trace.go:171","msg":"trace[402679690] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-localhost.186375f61906f7e8; range_end:; response_count:1; response_revision:352; }","duration":"115.397125ms","start":"2025-09-09T00:33:03.758360Z","end":"2025-09-09T00:33:03.873757Z","steps":["trace[402679690] 'agreement among raft nodes before linearized reading' (duration: 115.316876ms)"],"step_count":1} Sep 9 00:33:04.382826 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:04.382354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.718119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 9 00:33:04.382826 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:04.382430Z","caller":"traceutil/trace.go:171","msg":"trace[1773029162] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"182.113997ms","start":"2025-09-09T00:33:04.200306Z","end":"2025-09-09T00:33:04.382420Z","steps":["trace[1773029162] 'read index received' (duration: 77.317491ms)","trace[1773029162] 'applied index is now lower than readState.Index' (duration: 104.795935ms)"],"step_count":2} Sep 9 00:33:04.382826 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:04.382495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.19555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" limit:1 ","response":"range_response_count:1 size:218"} Sep 9 00:33:04.382826 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:04.382518Z","caller":"traceutil/trace.go:171","msg":"trace[1603079774] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:359; }","duration":"182.241194ms","start":"2025-09-09T00:33:04.200269Z","end":"2025-09-09T00:33:04.382511Z","steps":["trace[1603079774] 'agreement among raft nodes before linearized reading' (duration: 182.204967ms)"],"step_count":1} Sep 9 00:33:04.383224 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:04.382497Z","caller":"traceutil/trace.go:171","msg":"trace[1711553732] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"221.5521ms","start":"2025-09-09T00:33:04.160918Z","end":"2025-09-09T00:33:04.382470Z","steps":["trace[1711553732] 'process raft request' (duration: 116.664714ms)","trace[1711553732] 'compare' (duration: 104.605408ms)"],"step_count":2} Sep 9 00:33:04.383224 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:04.382525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.414983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" limit:1 ","response":"range_response_count:1 size:197"} Sep 9 00:33:04.383224 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:04.382611Z","caller":"traceutil/trace.go:171","msg":"trace[181047762] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:359; }","duration":"132.529658ms","start":"2025-09-09T00:33:04.250073Z","end":"2025-09-09T00:33:04.382602Z","steps":["trace[181047762] 'agreement among raft nodes before linearized reading' (duration: 132.427928ms)"],"step_count":1} Sep 9 00:33:05.349265 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:05.348884Z","caller":"traceutil/trace.go:171","msg":"trace[1886164984] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"101.223089ms","start":"2025-09-09T00:33:05.247642Z","end":"2025-09-09T00:33:05.348865Z","steps":["trace[1886164984] 'process raft request' (duration: 101.135305ms)"],"step_count":1} Sep 9 00:33:05.555943 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:05.555513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.729539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 9 00:33:05.555943 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:05.555671Z","caller":"traceutil/trace.go:171","msg":"trace[1535496171] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"281.50588ms","start":"2025-09-09T00:33:05.274154Z","end":"2025-09-09T00:33:05.555660Z","steps":["trace[1535496171] 'process raft request' (duration: 281.478399ms)"],"step_count":1} Sep 9 00:33:05.555943 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:05.555722Z","caller":"traceutil/trace.go:171","msg":"trace[85729871] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"287.759504ms","start":"2025-09-09T00:33:05.267958Z","end":"2025-09-09T00:33:05.555717Z","steps":["trace[85729871] 'process raft request' (duration: 287.625472ms)"],"step_count":1} Sep 9 00:33:05.555943 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:05.555703Z","caller":"traceutil/trace.go:171","msg":"trace[1457977835] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"288.888937ms","start":"2025-09-09T00:33:05.266798Z","end":"2025-09-09T00:33:05.555687Z","steps":["trace[1457977835] 'process raft request' (duration: 156.943074ms)","trace[1457977835] 'compare' (duration: 131.616587ms)"],"step_count":2} Sep 9 00:33:05.556356 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:05.555741Z","caller":"traceutil/trace.go:171","msg":"trace[326514098] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"286.269515ms","start":"2025-09-09T00:33:05.269468Z","end":"2025-09-09T00:33:05.555738Z","steps":["trace[326514098] 'process raft request' (duration: 286.145243ms)"],"step_count":1} Sep 9 00:33:05.568259 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:05.567821Z","caller":"traceutil/trace.go:171","msg":"trace[1584414368] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"216.433634ms","start":"2025-09-09T00:33:05.351368Z","end":"2025-09-09T00:33:05.567801Z","steps":["trace[1584414368] 'process raft request' (duration: 216.338947ms)"],"step_count":1} Sep 9 00:33:08.120276 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:08.119846Z","caller":"traceutil/trace.go:171","msg":"trace[661274735] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"149.767702ms","start":"2025-09-09T00:33:07.970052Z","end":"2025-09-09T00:33:08.119820Z","steps":["trace[661274735] 'process raft request' (duration: 125.551886ms)","trace[661274735] 'compare' (duration: 24.119676ms)"],"step_count":2} Sep 9 00:33:11.842697 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:11.842296Z","caller":"traceutil/trace.go:171","msg":"trace[1708899377] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"106.128047ms","start":"2025-09-09T00:33:11.736148Z","end":"2025-09-09T00:33:11.842276Z","steps":["trace[1708899377] 'process raft request' (duration: 106.034452ms)"],"step_count":1} Sep 9 00:33:12.048540 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:12.048149Z","caller":"traceutil/trace.go:171","msg":"trace[1191497639] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"308.529743ms","start":"2025-09-09T00:33:11.739599Z","end":"2025-09-09T00:33:12.048128Z","steps":["trace[1191497639] 'process raft request' (duration: 285.418066ms)","trace[1191497639] 'compare' (duration: 23.024023ms)"],"step_count":2} Sep 9 00:33:12.048769 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:12.048291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:33:11.739590Z","time spent":"308.613791ms","remote":"10.0.0.111:46176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6551,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 9 00:33:12.054241 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:12.053893Z","caller":"traceutil/trace.go:171","msg":"trace[1388464044] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"210.085176ms","start":"2025-09-09T00:33:11.843788Z","end":"2025-09-09T00:33:12.053874Z","steps":["trace[1388464044] 'process raft request' (duration: 209.912203ms)"],"step_count":1} Sep 9 00:33:23.351715 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:23.351282Z","caller":"traceutil/trace.go:171","msg":"trace[2025230397] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"108.627185ms","start":"2025-09-09T00:33:23.242630Z","end":"2025-09-09T00:33:23.351258Z","steps":["trace[2025230397] 'process raft request' (duration: 43.143404ms)","trace[2025230397] 'compare' (duration: 65.37467ms)"],"step_count":2} Sep 9 00:33:23.548154 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:23.547796Z","caller":"traceutil/trace.go:171","msg":"trace[1073515998] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"192.107547ms","start":"2025-09-09T00:33:23.355668Z","end":"2025-09-09T00:33:23.547776Z","steps":["trace[1073515998] 'process raft request' (duration: 166.100367ms)","trace[1073515998] 'compare' (duration: 25.915162ms)"],"step_count":2} Sep 9 00:33:28.376230 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:28.375754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.266217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 9 00:33:28.376230 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:28.375908Z","caller":"traceutil/trace.go:171","msg":"trace[910810287] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"180.692916ms","start":"2025-09-09T00:33:28.195200Z","end":"2025-09-09T00:33:28.375892Z","steps":["trace[910810287] 'process raft request' (duration: 180.644373ms)"],"step_count":1} Sep 9 00:33:28.376230 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:28.375953Z","caller":"traceutil/trace.go:171","msg":"trace[380134481] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"297.31712ms","start":"2025-09-09T00:33:28.078621Z","end":"2025-09-09T00:33:28.375938Z","steps":["trace[380134481] 'process raft request' (duration: 164.817034ms)","trace[380134481] 'compare' (duration: 132.146978ms)"],"step_count":2} Sep 9 00:33:30.912736 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:30.912341Z","caller":"traceutil/trace.go:171","msg":"trace[1807515859] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"143.786646ms","start":"2025-09-09T00:33:30.768534Z","end":"2025-09-09T00:33:30.912321Z","steps":["trace[1807515859] 'read index received' (duration: 40.439675ms)","trace[1807515859] 'applied index is now lower than readState.Index' (duration: 103.346329ms)"],"step_count":2} Sep 9 00:33:30.913326 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:30.912457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.899622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 9 00:33:30.913326 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:30.912484Z","caller":"traceutil/trace.go:171","msg":"trace[1325765686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"143.956361ms","start":"2025-09-09T00:33:30.768520Z","end":"2025-09-09T00:33:30.912476Z","steps":["trace[1325765686] 'agreement among raft nodes before linearized reading' (duration: 143.881277ms)"],"step_count":1} Sep 9 00:33:30.913326 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:30.912531Z","caller":"traceutil/trace.go:171","msg":"trace[298236014] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"150.827987ms","start":"2025-09-09T00:33:30.761672Z","end":"2025-09-09T00:33:30.912500Z","steps":["trace[298236014] 'process raft request' (duration: 131.227966ms)","trace[298236014] 'compare' (duration: 19.336726ms)"],"step_count":2} Sep 9 00:33:31.160045 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:31.159662Z","caller":"traceutil/trace.go:171","msg":"trace[922149209] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"199.555049ms","start":"2025-09-09T00:33:30.960084Z","end":"2025-09-09T00:33:31.159640Z","steps":["trace[922149209] 'process raft request' (duration: 124.702323ms)","trace[922149209] 'compare' (duration: 74.746121ms)"],"step_count":2} Sep 9 00:33:34.204343 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:34.203935Z","caller":"traceutil/trace.go:171","msg":"trace[294838417] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"148.359055ms","start":"2025-09-09T00:33:34.055555Z","end":"2025-09-09T00:33:34.203914Z","steps":["trace[294838417] 'process raft request' (duration: 54.485599ms)","trace[294838417] 'compare' (duration: 93.775889ms)"],"step_count":2} Sep 9 00:33:36.318661 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:36.318130Z","caller":"traceutil/trace.go:171","msg":"trace[2127821743] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"142.872861ms","start":"2025-09-09T00:33:36.175221Z","end":"2025-09-09T00:33:36.318094Z","steps":["trace[2127821743] 'process raft request' (duration: 142.693058ms)"],"step_count":1} Sep 9 00:33:38.890830 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:38.890343Z","caller":"traceutil/trace.go:171","msg":"trace[85668680] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"138.293845ms","start":"2025-09-09T00:33:38.752026Z","end":"2025-09-09T00:33:38.890320Z","steps":["trace[85668680] 'process raft request' (duration: 138.177733ms)"],"step_count":1} Sep 9 00:33:39.095154 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:39.094668Z","caller":"traceutil/trace.go:171","msg":"trace[1621793097] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"336.93397ms","start":"2025-09-09T00:33:38.757715Z","end":"2025-09-09T00:33:39.094649Z","steps":["trace[1621793097] 'process raft request' (duration: 244.445161ms)","trace[1621793097] 'compare' (duration: 92.352368ms)"],"step_count":2} Sep 9 00:33:39.095154 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:39.094813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:33:38.757708Z","time spent":"337.025173ms","remote":"10.0.0.111:46176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7677,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 9 00:33:39.095471 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:39.094814Z","caller":"traceutil/trace.go:171","msg":"trace[41341681] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"174.828907ms","start":"2025-09-09T00:33:38.919958Z","end":"2025-09-09T00:33:39.094787Z","steps":["trace[41341681] 'process raft request' (duration: 174.65231ms)"],"step_count":1} Sep 9 00:33:39.544817 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:39.544460Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.933632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:3152"} Sep 9 00:33:39.544817 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:39.544547Z","caller":"traceutil/trace.go:171","msg":"trace[1467123300] transaction","detail":"{read_only:false; number_of_response:1; response_revision:611; }","duration":"479.272514ms","start":"2025-09-09T00:33:39.065262Z","end":"2025-09-09T00:33:39.544535Z","steps":["trace[1467123300] 'process raft request' (duration: 131.191451ms)","trace[1467123300] 'compare' (duration: 347.855132ms)"],"step_count":2} Sep 9 00:33:39.544817 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:39.544593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:33:39.065238Z","time spent":"479.33307ms","remote":"10.0.0.111:46160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":32,"response count":0,"response size":3176,"request content":"compare: success:> failure: >"} Sep 9 00:33:39.572461 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:39.572137Z","caller":"traceutil/trace.go:171","msg":"trace[2034143031] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"469.041356ms","start":"2025-09-09T00:33:39.103081Z","end":"2025-09-09T00:33:39.572122Z","steps":["trace[2034143031] 'process raft request' (duration: 468.906198ms)"],"step_count":1} Sep 9 00:33:39.572461 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:39.572241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:33:39.103061Z","time spent":"469.124916ms","remote":"10.0.0.111:46454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7079,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 9 00:33:39.572461 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:39.572245Z","caller":"traceutil/trace.go:171","msg":"trace[1137635205] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"443.772706ms","start":"2025-09-09T00:33:39.128463Z","end":"2025-09-09T00:33:39.572235Z","steps":["trace[1137635205] 'process raft request' (duration: 443.624082ms)"],"step_count":1} Sep 9 00:33:39.572836 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:39.572320Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:33:39.128445Z","time spent":"443.852659ms","remote":"10.0.0.111:46160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3404,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 9 00:33:46.333349 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:46.332874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.960385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 9 00:33:46.333349 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:46.332957Z","caller":"traceutil/trace.go:171","msg":"trace[422605130] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"470.41173ms","start":"2025-09-09T00:33:45.862535Z","end":"2025-09-09T00:33:46.332947Z","steps":["trace[422605130] 'read index received' (duration: 256.303945ms)","trace[422605130] 'applied index is now lower than readState.Index' (duration: 214.107264ms)"],"step_count":2} Sep 9 00:33:46.333349 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:46.333006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.458028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments\" limit:1 ","response":"range_response_count:0 size:5"} Sep 9 00:33:46.333349 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:46.333022Z","caller":"traceutil/trace.go:171","msg":"trace[1753961829] range","detail":"{range_begin:/registry/deployments; range_end:; response_count:0; response_revision:642; }","duration":"470.515417ms","start":"2025-09-09T00:33:45.862500Z","end":"2025-09-09T00:33:46.333015Z","steps":["trace[1753961829] 'agreement among raft nodes before linearized reading' (duration: 470.474088ms)"],"step_count":1} Sep 9 00:33:46.334127 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:46.333044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:33:45.862483Z","time spent":"470.55425ms","remote":"10.0.0.111:46442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":25,"response count":0,"response size":29,"request content":"key:\"/registry/deployments\" limit:1 "} Sep 9 00:33:46.334127 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:46.333025Z","caller":"traceutil/trace.go:171","msg":"trace[1696222220] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"491.936075ms","start":"2025-09-09T00:33:45.841045Z","end":"2025-09-09T00:33:46.332981Z","steps":["trace[1696222220] 'process raft request' (duration: 277.816718ms)","trace[1696222220] 'compare' (duration: 213.875633ms)"],"step_count":2} Sep 9 00:33:46.334127 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:46.333169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-09T00:33:45.841036Z","time spent":"492.053007ms","remote":"10.0.0.111:46470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1914,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 9 00:33:53.171161 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:53.169321Z","caller":"traceutil/trace.go:171","msg":"trace[131245006] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"141.676003ms","start":"2025-09-09T00:33:53.027624Z","end":"2025-09-09T00:33:53.169300Z","steps":["trace[131245006] 'read index received' (duration: 96.613927ms)","trace[131245006] 'applied index is now lower than readState.Index' (duration: 45.061064ms)"],"step_count":2} Sep 9 00:33:53.171161 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:33:53.169512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.872996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pod-1\" limit:1 ","response":"range_response_count:1 size:2350"} Sep 9 00:33:53.171161 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:33:53.169573Z","caller":"traceutil/trace.go:171","msg":"trace[2099698030] range","detail":"{range_begin:/registry/pods/default/test-pod-1; range_end:; response_count:1; response_revision:680; }","duration":"141.97538ms","start":"2025-09-09T00:33:53.027585Z","end":"2025-09-09T00:33:53.169560Z","steps":["trace[2099698030] 'agreement among raft nodes before linearized reading' (duration: 141.788865ms)"],"step_count":1} Sep 9 00:34:15.067914 etcd-wrapper[1631]: {"level":"warn","ts":"2025-09-09T00:34:15.067461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.986136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 9 00:34:15.067914 etcd-wrapper[1631]: {"level":"info","ts":"2025-09-09T00:34:15.067566Z","caller":"traceutil/trace.go:171","msg":"trace[1582998472] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"141.592887ms","start":"2025-09-09T00:34:14.925959Z","end":"2025-09-09T00:34:15.067552Z","steps":["trace[1582998472] 'process raft request' (duration: 21.451558ms)","trace[1582998472] 'compare' (duration: 119.903851ms)"],"step_count":2}