Sep 5 06:07:50.845496 kernel: Linux version 6.12.44-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Sep 5 04:19:33 -00 2025 Sep 5 06:07:50.845533 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=4b2174e9c368fa97600991ce20efc370fbbf3ddfce3ea407f50212a1021bd496 Sep 5 06:07:50.845545 kernel: BIOS-provided physical RAM map: Sep 5 06:07:50.845554 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 5 06:07:50.845562 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 5 06:07:50.845571 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 5 06:07:50.845581 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Sep 5 06:07:50.845590 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Sep 5 06:07:50.845605 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 5 06:07:50.845613 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Sep 5 06:07:50.845622 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 5 06:07:50.845631 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 5 06:07:50.845663 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 5 06:07:50.845674 kernel: NX (Execute Disable) protection: active Sep 5 06:07:50.845698 kernel: APIC: Static calls initialized Sep 5 06:07:50.845708 kernel: SMBIOS 2.8 present. Sep 5 06:07:50.845721 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Sep 5 06:07:50.845731 kernel: DMI: Memory slots populated: 1/1 Sep 5 06:07:50.845740 kernel: Hypervisor detected: KVM Sep 5 06:07:50.845749 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 5 06:07:50.845759 kernel: kvm-clock: using sched offset of 6090729888 cycles Sep 5 06:07:50.845769 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 5 06:07:50.845779 kernel: tsc: Detected 2794.748 MHz processor Sep 5 06:07:50.845796 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 5 06:07:50.845813 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 5 06:07:50.845823 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Sep 5 06:07:50.845833 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 5 06:07:50.845843 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 5 06:07:50.845853 kernel: Using GB pages for direct mapping Sep 5 06:07:50.845863 kernel: ACPI: Early table checksum verification disabled Sep 5 06:07:50.845873 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Sep 5 06:07:50.845883 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 06:07:50.845896 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 06:07:50.845906 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 06:07:50.845916 kernel: ACPI: FACS 0x000000009CFE0000 000040 Sep 5 06:07:50.845926 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 06:07:50.845936 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 06:07:50.845946 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 06:07:50.845962 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 5 06:07:50.845972 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Sep 5 06:07:50.845989 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Sep 5 06:07:50.846000 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Sep 5 06:07:50.846010 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Sep 5 06:07:50.846020 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Sep 5 06:07:50.846030 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Sep 5 06:07:50.846041 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Sep 5 06:07:50.846061 kernel: No NUMA configuration found Sep 5 06:07:50.846071 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Sep 5 06:07:50.846082 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff] Sep 5 06:07:50.846092 kernel: Zone ranges: Sep 5 06:07:50.846102 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 5 06:07:50.846112 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Sep 5 06:07:50.846122 kernel: Normal empty Sep 5 06:07:50.846132 kernel: Device empty Sep 5 06:07:50.846142 kernel: Movable zone start for each node Sep 5 06:07:50.846152 kernel: Early memory node ranges Sep 5 06:07:50.846165 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 5 06:07:50.846176 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Sep 5 06:07:50.846187 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Sep 5 06:07:50.846197 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 5 06:07:50.846206 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 5 06:07:50.846217 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Sep 5 06:07:50.846227 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 5 06:07:50.846241 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 5 06:07:50.846251 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 5 06:07:50.846265 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 5 06:07:50.846275 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 5 06:07:50.846287 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 5 06:07:50.846297 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 5 06:07:50.846307 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 5 06:07:50.846316 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 5 06:07:50.846326 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 5 06:07:50.846339 kernel: TSC deadline timer available Sep 5 06:07:50.846347 kernel: CPU topo: Max. logical packages: 1 Sep 5 06:07:50.846357 kernel: CPU topo: Max. logical dies: 1 Sep 5 06:07:50.846364 kernel: CPU topo: Max. dies per package: 1 Sep 5 06:07:50.846372 kernel: CPU topo: Max. threads per core: 1 Sep 5 06:07:50.846379 kernel: CPU topo: Num. cores per package: 4 Sep 5 06:07:50.846386 kernel: CPU topo: Num. threads per package: 4 Sep 5 06:07:50.846394 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 5 06:07:50.846404 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 5 06:07:50.846414 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 5 06:07:50.846423 kernel: kvm-guest: setup PV sched yield Sep 5 06:07:50.846444 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Sep 5 06:07:50.846459 kernel: Booting paravirtualized kernel on KVM Sep 5 06:07:50.846473 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 5 06:07:50.846484 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 5 06:07:50.846494 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 5 06:07:50.846504 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 5 06:07:50.846514 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 5 06:07:50.846524 kernel: kvm-guest: PV spinlocks enabled Sep 5 06:07:50.846534 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 5 06:07:50.846545 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=4b2174e9c368fa97600991ce20efc370fbbf3ddfce3ea407f50212a1021bd496 Sep 5 06:07:50.846558 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 5 06:07:50.846568 kernel: random: crng init done Sep 5 06:07:50.846578 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 5 06:07:50.846588 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 5 06:07:50.846596 kernel: Fallback order for Node 0: 0 Sep 5 06:07:50.846604 kernel: Built 1 zonelists, mobility grouping on. Total pages: 642938 Sep 5 06:07:50.846611 kernel: Policy zone: DMA32 Sep 5 06:07:50.846619 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 5 06:07:50.846629 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 5 06:07:50.846637 kernel: ftrace: allocating 40102 entries in 157 pages Sep 5 06:07:50.846670 kernel: ftrace: allocated 157 pages with 5 groups Sep 5 06:07:50.846691 kernel: Dynamic Preempt: voluntary Sep 5 06:07:50.846701 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 5 06:07:50.846712 kernel: rcu: RCU event tracing is enabled. Sep 5 06:07:50.846722 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 5 06:07:50.846732 kernel: Trampoline variant of Tasks RCU enabled. Sep 5 06:07:50.846746 kernel: Rude variant of Tasks RCU enabled. Sep 5 06:07:50.846760 kernel: Tracing variant of Tasks RCU enabled. Sep 5 06:07:50.846780 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 5 06:07:50.846789 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 5 06:07:50.846796 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 06:07:50.846804 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 06:07:50.846812 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 5 06:07:50.846820 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 5 06:07:50.846827 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 5 06:07:50.846844 kernel: Console: colour VGA+ 80x25 Sep 5 06:07:50.846852 kernel: printk: legacy console [ttyS0] enabled Sep 5 06:07:50.846860 kernel: ACPI: Core revision 20240827 Sep 5 06:07:50.846870 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 5 06:07:50.846878 kernel: APIC: Switch to symmetric I/O mode setup Sep 5 06:07:50.846886 kernel: x2apic enabled Sep 5 06:07:50.846900 kernel: APIC: Switched APIC routing to: physical x2apic Sep 5 06:07:50.846932 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 5 06:07:50.846943 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 5 06:07:50.846966 kernel: kvm-guest: setup PV IPIs Sep 5 06:07:50.846976 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 5 06:07:50.846986 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Sep 5 06:07:50.846996 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Sep 5 06:07:50.847006 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 5 06:07:50.847020 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 5 06:07:50.847035 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 5 06:07:50.847045 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 5 06:07:50.847061 kernel: Spectre V2 : Mitigation: Retpolines Sep 5 06:07:50.847072 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 5 06:07:50.847082 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 5 06:07:50.847093 kernel: active return thunk: retbleed_return_thunk Sep 5 06:07:50.847103 kernel: RETBleed: Mitigation: untrained return thunk Sep 5 06:07:50.847114 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 5 06:07:50.847124 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 5 06:07:50.847135 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 5 06:07:50.847147 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Sep 5 06:07:50.847162 kernel: active return thunk: srso_return_thunk Sep 5 06:07:50.847172 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 5 06:07:50.847183 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 5 06:07:50.847198 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 5 06:07:50.847209 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 5 06:07:50.847225 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 5 06:07:50.847242 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 5 06:07:50.847253 kernel: Freeing SMP alternatives memory: 32K Sep 5 06:07:50.847266 kernel: pid_max: default: 32768 minimum: 301 Sep 5 06:07:50.847277 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 5 06:07:50.847287 kernel: landlock: Up and running. Sep 5 06:07:50.847298 kernel: SELinux: Initializing. Sep 5 06:07:50.847313 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 06:07:50.847328 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 5 06:07:50.847340 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 5 06:07:50.847362 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 5 06:07:50.847373 kernel: ... version: 0 Sep 5 06:07:50.847387 kernel: ... bit width: 48 Sep 5 06:07:50.847397 kernel: ... generic registers: 6 Sep 5 06:07:50.847408 kernel: ... value mask: 0000ffffffffffff Sep 5 06:07:50.847421 kernel: ... max period: 00007fffffffffff Sep 5 06:07:50.847431 kernel: ... fixed-purpose events: 0 Sep 5 06:07:50.847443 kernel: ... event mask: 000000000000003f Sep 5 06:07:50.847455 kernel: signal: max sigframe size: 1776 Sep 5 06:07:50.847473 kernel: rcu: Hierarchical SRCU implementation. Sep 5 06:07:50.847486 kernel: rcu: Max phase no-delay instances is 400. Sep 5 06:07:50.847496 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 5 06:07:50.847510 kernel: smp: Bringing up secondary CPUs ... Sep 5 06:07:50.847525 kernel: smpboot: x86: Booting SMP configuration: Sep 5 06:07:50.847538 kernel: .... node #0, CPUs: #1 #2 #3 Sep 5 06:07:50.847549 kernel: smp: Brought up 1 node, 4 CPUs Sep 5 06:07:50.847560 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Sep 5 06:07:50.847571 kernel: Memory: 2428920K/2571752K available (14336K kernel code, 2428K rwdata, 9988K rodata, 54068K init, 2900K bss, 136904K reserved, 0K cma-reserved) Sep 5 06:07:50.847581 kernel: devtmpfs: initialized Sep 5 06:07:50.847592 kernel: x86/mm: Memory block size: 128MB Sep 5 06:07:50.847603 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 5 06:07:50.847617 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 5 06:07:50.847628 kernel: pinctrl core: initialized pinctrl subsystem Sep 5 06:07:50.847660 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 5 06:07:50.847688 kernel: audit: initializing netlink subsys (disabled) Sep 5 06:07:50.847710 kernel: audit: type=2000 audit(1757052467.957:1): state=initialized audit_enabled=0 res=1 Sep 5 06:07:50.847722 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 5 06:07:50.847732 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 5 06:07:50.847743 kernel: cpuidle: using governor menu Sep 5 06:07:50.847758 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 5 06:07:50.847768 kernel: dca service started, version 1.12.1 Sep 5 06:07:50.847779 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] Sep 5 06:07:50.847790 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 5 06:07:50.847801 kernel: PCI: Using configuration type 1 for base access Sep 5 06:07:50.847812 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 5 06:07:50.847823 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 5 06:07:50.847834 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 5 06:07:50.847844 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 5 06:07:50.847861 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 5 06:07:50.847872 kernel: ACPI: Added _OSI(Module Device) Sep 5 06:07:50.847883 kernel: ACPI: Added _OSI(Processor Device) Sep 5 06:07:50.847893 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 5 06:07:50.847904 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 5 06:07:50.847914 kernel: ACPI: Interpreter enabled Sep 5 06:07:50.847925 kernel: ACPI: PM: (supports S0 S3 S5) Sep 5 06:07:50.847935 kernel: ACPI: Using IOAPIC for interrupt routing Sep 5 06:07:50.847946 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 5 06:07:50.847957 kernel: PCI: Using E820 reservations for host bridge windows Sep 5 06:07:50.847971 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 5 06:07:50.847981 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 5 06:07:50.848247 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 5 06:07:50.848419 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 5 06:07:50.848577 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 5 06:07:50.848592 kernel: PCI host bridge to bus 0000:00 Sep 5 06:07:50.848783 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 5 06:07:50.848931 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 5 06:07:50.850370 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 5 06:07:50.850531 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 5 06:07:50.850708 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 5 06:07:50.850840 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Sep 5 06:07:50.850969 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 5 06:07:50.851148 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 5 06:07:50.851321 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 5 06:07:50.851470 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Sep 5 06:07:50.851622 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Sep 5 06:07:50.851826 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Sep 5 06:07:50.851981 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 5 06:07:50.852147 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 5 06:07:50.852305 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Sep 5 06:07:50.852502 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Sep 5 06:07:50.852736 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Sep 5 06:07:50.852921 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 5 06:07:50.853076 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Sep 5 06:07:50.853262 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Sep 5 06:07:50.853419 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Sep 5 06:07:50.853559 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 5 06:07:50.853727 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Sep 5 06:07:50.853884 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Sep 5 06:07:50.854035 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Sep 5 06:07:50.854182 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Sep 5 06:07:50.854343 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 5 06:07:50.854501 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 5 06:07:50.854683 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 5 06:07:50.854842 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Sep 5 06:07:50.855001 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Sep 5 06:07:50.855164 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 5 06:07:50.855313 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Sep 5 06:07:50.855329 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 5 06:07:50.855346 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 5 06:07:50.855358 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 5 06:07:50.855368 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 5 06:07:50.855379 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 5 06:07:50.855390 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 5 06:07:50.855401 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 5 06:07:50.855412 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 5 06:07:50.855427 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 5 06:07:50.855439 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 5 06:07:50.855454 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 5 06:07:50.855465 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 5 06:07:50.855476 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 5 06:07:50.855487 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 5 06:07:50.855498 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 5 06:07:50.855509 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 5 06:07:50.855521 kernel: iommu: Default domain type: Translated Sep 5 06:07:50.855532 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 5 06:07:50.855543 kernel: PCI: Using ACPI for IRQ routing Sep 5 06:07:50.855557 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 5 06:07:50.855568 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 5 06:07:50.855580 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Sep 5 06:07:50.855760 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 5 06:07:50.855916 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 5 06:07:50.856068 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 5 06:07:50.856094 kernel: vgaarb: loaded Sep 5 06:07:50.856107 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 5 06:07:50.856123 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 5 06:07:50.856134 kernel: clocksource: Switched to clocksource kvm-clock Sep 5 06:07:50.856144 kernel: VFS: Disk quotas dquot_6.6.0 Sep 5 06:07:50.856155 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 5 06:07:50.856166 kernel: pnp: PnP ACPI init Sep 5 06:07:50.856347 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 5 06:07:50.856365 kernel: pnp: PnP ACPI: found 6 devices Sep 5 06:07:50.856376 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 5 06:07:50.856391 kernel: NET: Registered PF_INET protocol family Sep 5 06:07:50.856402 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 5 06:07:50.856413 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 5 06:07:50.856424 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 5 06:07:50.856435 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 5 06:07:50.856446 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 5 06:07:50.856457 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 5 06:07:50.856468 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 06:07:50.856478 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 5 06:07:50.856493 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 5 06:07:50.856504 kernel: NET: Registered PF_XDP protocol family Sep 5 06:07:50.856671 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 5 06:07:50.856830 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 5 06:07:50.856976 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 5 06:07:50.857131 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 5 06:07:50.857279 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 5 06:07:50.857437 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Sep 5 06:07:50.857462 kernel: PCI: CLS 0 bytes, default 64 Sep 5 06:07:50.857476 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848df6a9de, max_idle_ns: 440795280912 ns Sep 5 06:07:50.857487 kernel: Initialise system trusted keyrings Sep 5 06:07:50.857498 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 5 06:07:50.857509 kernel: Key type asymmetric registered Sep 5 06:07:50.857523 kernel: Asymmetric key parser 'x509' registered Sep 5 06:07:50.857535 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 5 06:07:50.857546 kernel: io scheduler mq-deadline registered Sep 5 06:07:50.857559 kernel: io scheduler kyber registered Sep 5 06:07:50.857574 kernel: io scheduler bfq registered Sep 5 06:07:50.857585 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 5 06:07:50.857597 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 5 06:07:50.857609 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 5 06:07:50.857619 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 5 06:07:50.857631 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 5 06:07:50.857655 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 5 06:07:50.857668 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 5 06:07:50.857688 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 5 06:07:50.857699 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 5 06:07:50.857864 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 5 06:07:50.857882 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 5 06:07:50.858022 kernel: rtc_cmos 00:04: registered as rtc0 Sep 5 06:07:50.858253 kernel: rtc_cmos 00:04: setting system clock to 2025-09-05T06:07:50 UTC (1757052470) Sep 5 06:07:50.858400 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 5 06:07:50.858416 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 5 06:07:50.858426 kernel: NET: Registered PF_INET6 protocol family Sep 5 06:07:50.858445 kernel: Segment Routing with IPv6 Sep 5 06:07:50.858461 kernel: In-situ OAM (IOAM) with IPv6 Sep 5 06:07:50.858471 kernel: NET: Registered PF_PACKET protocol family Sep 5 06:07:50.858484 kernel: Key type dns_resolver registered Sep 5 06:07:50.858496 kernel: IPI shorthand broadcast: enabled Sep 5 06:07:50.858509 kernel: sched_clock: Marking stable (3512003816, 123640278)->(3656882036, -21237942) Sep 5 06:07:50.858519 kernel: registered taskstats version 1 Sep 5 06:07:50.858530 kernel: Loading compiled-in X.509 certificates Sep 5 06:07:50.858541 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.44-flatcar: 0a288d3740f799f7923bd7314e999f997bd1026c' Sep 5 06:07:50.858555 kernel: Demotion targets for Node 0: null Sep 5 06:07:50.858566 kernel: Key type .fscrypt registered Sep 5 06:07:50.858577 kernel: Key type fscrypt-provisioning registered Sep 5 06:07:50.858588 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 5 06:07:50.858598 kernel: ima: Allocated hash algorithm: sha1 Sep 5 06:07:50.858610 kernel: ima: No architecture policies found Sep 5 06:07:50.858621 kernel: clk: Disabling unused clocks Sep 5 06:07:50.858630 kernel: Warning: unable to open an initial console. Sep 5 06:07:50.858657 kernel: Freeing unused kernel image (initmem) memory: 54068K Sep 5 06:07:50.858673 kernel: Write protecting the kernel read-only data: 24576k Sep 5 06:07:50.858697 kernel: Freeing unused kernel image (rodata/data gap) memory: 252K Sep 5 06:07:50.858708 kernel: Run /init as init process Sep 5 06:07:50.858718 kernel: with arguments: Sep 5 06:07:50.858737 kernel: /init Sep 5 06:07:50.858757 kernel: with environment: Sep 5 06:07:50.858767 kernel: HOME=/ Sep 5 06:07:50.858777 kernel: TERM=linux Sep 5 06:07:50.858787 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 5 06:07:50.858806 systemd[1]: Successfully made /usr/ read-only. Sep 5 06:07:50.858834 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 5 06:07:50.858850 systemd[1]: Detected virtualization kvm. Sep 5 06:07:50.858862 systemd[1]: Detected architecture x86-64. Sep 5 06:07:50.858874 systemd[1]: Running in initrd. Sep 5 06:07:50.858889 systemd[1]: No hostname configured, using default hostname. Sep 5 06:07:50.858901 systemd[1]: Hostname set to . Sep 5 06:07:50.858913 systemd[1]: Initializing machine ID from VM UUID. Sep 5 06:07:50.858925 systemd[1]: Queued start job for default target initrd.target. Sep 5 06:07:50.858937 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 06:07:50.858949 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 06:07:50.858963 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 5 06:07:50.858975 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 06:07:50.858989 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 5 06:07:50.859003 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 5 06:07:50.859019 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 5 06:07:50.859031 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 5 06:07:50.859044 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 06:07:50.859057 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 06:07:50.859069 systemd[1]: Reached target paths.target - Path Units. Sep 5 06:07:50.859085 systemd[1]: Reached target slices.target - Slice Units. Sep 5 06:07:50.859097 systemd[1]: Reached target swap.target - Swaps. Sep 5 06:07:50.859110 systemd[1]: Reached target timers.target - Timer Units. Sep 5 06:07:50.859124 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 06:07:50.859135 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 06:07:50.859146 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 5 06:07:50.859160 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 5 06:07:50.859172 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 06:07:50.859183 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 06:07:50.859198 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 06:07:50.859213 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 06:07:50.859227 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 5 06:07:50.859239 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 06:07:50.859253 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 5 06:07:50.859267 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 5 06:07:50.859278 systemd[1]: Starting systemd-fsck-usr.service... Sep 5 06:07:50.859288 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 06:07:50.859299 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 06:07:50.859310 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 06:07:50.859321 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 5 06:07:50.859335 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 06:07:50.859346 systemd[1]: Finished systemd-fsck-usr.service. Sep 5 06:07:50.859357 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 5 06:07:50.859416 systemd-journald[220]: Collecting audit messages is disabled. Sep 5 06:07:50.859450 systemd-journald[220]: Journal started Sep 5 06:07:50.859478 systemd-journald[220]: Runtime Journal (/run/log/journal/641d8b49bf884d71893647f08eac877d) is 6M, max 48.6M, 42.5M free. Sep 5 06:07:50.842513 systemd-modules-load[222]: Inserted module 'overlay' Sep 5 06:07:50.861293 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 06:07:50.861814 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 5 06:07:50.866934 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 06:07:51.092706 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 5 06:07:51.095075 systemd-modules-load[222]: Inserted module 'br_netfilter' Sep 5 06:07:51.129550 kernel: Bridge firewalling registered Sep 5 06:07:51.096950 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 06:07:51.136949 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 06:07:51.137477 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 06:07:51.139875 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 06:07:51.144809 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 5 06:07:51.145765 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 06:07:51.156703 systemd-tmpfiles[240]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 5 06:07:51.163327 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 06:07:51.168726 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 06:07:51.170711 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 06:07:51.176208 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 06:07:51.182436 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 5 06:07:51.215711 dracut-cmdline[263]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=4b2174e9c368fa97600991ce20efc370fbbf3ddfce3ea407f50212a1021bd496 Sep 5 06:07:51.221260 systemd-resolved[261]: Positive Trust Anchors: Sep 5 06:07:51.221284 systemd-resolved[261]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 06:07:51.221325 systemd-resolved[261]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 5 06:07:51.225105 systemd-resolved[261]: Defaulting to hostname 'linux'. Sep 5 06:07:51.226897 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 06:07:51.233067 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 06:07:51.342715 kernel: SCSI subsystem initialized Sep 5 06:07:51.354718 kernel: Loading iSCSI transport class v2.0-870. Sep 5 06:07:51.368718 kernel: iscsi: registered transport (tcp) Sep 5 06:07:51.394698 kernel: iscsi: registered transport (qla4xxx) Sep 5 06:07:51.394786 kernel: QLogic iSCSI HBA Driver Sep 5 06:07:51.419216 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 5 06:07:51.447044 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 06:07:51.447670 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 5 06:07:51.527115 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 5 06:07:51.529712 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 5 06:07:51.605666 kernel: raid6: avx2x4 gen() 20077 MB/s Sep 5 06:07:51.620721 kernel: raid6: avx2x2 gen() 21812 MB/s Sep 5 06:07:51.637960 kernel: raid6: avx2x1 gen() 18433 MB/s Sep 5 06:07:51.638049 kernel: raid6: using algorithm avx2x2 gen() 21812 MB/s Sep 5 06:07:51.655983 kernel: raid6: .... xor() 15750 MB/s, rmw enabled Sep 5 06:07:51.656091 kernel: raid6: using avx2x2 recovery algorithm Sep 5 06:07:51.684766 kernel: xor: automatically using best checksumming function avx Sep 5 06:07:51.948724 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 5 06:07:51.958389 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 5 06:07:51.961389 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 06:07:52.000162 systemd-udevd[473]: Using default interface naming scheme 'v255'. Sep 5 06:07:52.005861 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 06:07:52.010769 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 5 06:07:52.046041 dracut-pre-trigger[482]: rd.md=0: removing MD RAID activation Sep 5 06:07:52.086117 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 06:07:52.089299 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 06:07:52.173072 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 06:07:52.177180 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 5 06:07:52.227334 kernel: cryptd: max_cpu_qlen set to 1000 Sep 5 06:07:52.228667 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 5 06:07:52.249196 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 5 06:07:52.253671 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Sep 5 06:07:52.256697 kernel: AES CTR mode by8 optimization enabled Sep 5 06:07:52.257674 kernel: libata version 3.00 loaded. Sep 5 06:07:52.261734 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 06:07:52.265165 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 06:07:52.265299 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 06:07:52.268674 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 06:07:52.272198 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 06:07:52.272690 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 5 06:07:52.278689 kernel: ahci 0000:00:1f.2: version 3.0 Sep 5 06:07:52.281681 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 5 06:07:52.281722 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 5 06:07:52.282816 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 5 06:07:52.283031 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 5 06:07:52.288682 kernel: scsi host0: ahci Sep 5 06:07:52.292092 kernel: scsi host1: ahci Sep 5 06:07:52.292439 kernel: scsi host2: ahci Sep 5 06:07:52.292903 kernel: scsi host3: ahci Sep 5 06:07:52.294871 kernel: scsi host4: ahci Sep 5 06:07:52.295172 kernel: scsi host5: ahci Sep 5 06:07:52.295349 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 lpm-pol 1 Sep 5 06:07:52.296900 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 lpm-pol 1 Sep 5 06:07:52.296928 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 lpm-pol 1 Sep 5 06:07:52.299684 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 lpm-pol 1 Sep 5 06:07:52.299719 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 lpm-pol 1 Sep 5 06:07:52.299733 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 lpm-pol 1 Sep 5 06:07:52.326393 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 5 06:07:52.349764 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 5 06:07:52.357481 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 5 06:07:52.357598 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 5 06:07:52.366154 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 5 06:07:52.367664 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 5 06:07:52.401674 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 06:07:52.407667 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 06:07:52.459977 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 06:07:52.605686 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 5 06:07:52.605767 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 5 06:07:52.606698 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 5 06:07:52.607685 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 5 06:07:52.608682 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 5 06:07:52.608702 kernel: ata3.00: LPM support broken, forcing max_power Sep 5 06:07:52.609935 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 5 06:07:52.609953 kernel: ata3.00: applying bridge limits Sep 5 06:07:52.611081 kernel: ata3.00: LPM support broken, forcing max_power Sep 5 06:07:52.611098 kernel: ata3.00: configured for UDMA/100 Sep 5 06:07:52.613679 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 5 06:07:52.616669 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 5 06:07:52.671702 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 5 06:07:52.671921 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 5 06:07:52.687686 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 5 06:07:53.099803 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 5 06:07:53.101712 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 06:07:53.103767 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 06:07:53.106132 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 06:07:53.109500 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 5 06:07:53.140436 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 5 06:07:53.413686 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 5 06:07:53.414215 disk-uuid[634]: The operation has completed successfully. Sep 5 06:07:53.443844 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 5 06:07:53.444009 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 5 06:07:53.495225 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 5 06:07:53.520454 sh[664]: Success Sep 5 06:07:53.542015 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 5 06:07:53.542098 kernel: device-mapper: uevent: version 1.0.3 Sep 5 06:07:53.543140 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 5 06:07:53.553685 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 5 06:07:53.587770 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 5 06:07:53.589970 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 5 06:07:53.603090 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 5 06:07:53.610684 kernel: BTRFS: device fsid 98069635-e988-4e04-b156-f40a4a69cf42 devid 1 transid 35 /dev/mapper/usr (253:0) scanned by mount (676) Sep 5 06:07:53.612861 kernel: BTRFS info (device dm-0): first mount of filesystem 98069635-e988-4e04-b156-f40a4a69cf42 Sep 5 06:07:53.612892 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 5 06:07:53.621106 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 5 06:07:53.621195 kernel: BTRFS info (device dm-0): enabling free space tree Sep 5 06:07:53.622790 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 5 06:07:53.624591 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 5 06:07:53.626111 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 5 06:07:53.627290 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 5 06:07:53.631190 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 5 06:07:53.660696 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (709) Sep 5 06:07:53.663310 kernel: BTRFS info (device vda6): first mount of filesystem b74bbc0c-6da1-4206-9f48-c70f629ccdff Sep 5 06:07:53.663346 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 06:07:53.667242 kernel: BTRFS info (device vda6): turning on async discard Sep 5 06:07:53.667281 kernel: BTRFS info (device vda6): enabling free space tree Sep 5 06:07:53.673669 kernel: BTRFS info (device vda6): last unmount of filesystem b74bbc0c-6da1-4206-9f48-c70f629ccdff Sep 5 06:07:53.674054 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 5 06:07:53.677844 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 5 06:07:53.788838 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 06:07:53.794461 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 06:07:53.877686 systemd-networkd[845]: lo: Link UP Sep 5 06:07:53.877700 systemd-networkd[845]: lo: Gained carrier Sep 5 06:07:53.880830 systemd-networkd[845]: Enumeration completed Sep 5 06:07:53.881355 systemd-networkd[845]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 06:07:53.881360 systemd-networkd[845]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 06:07:53.888106 systemd-networkd[845]: eth0: Link UP Sep 5 06:07:53.901788 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 06:07:53.904874 systemd[1]: Reached target network.target - Network. Sep 5 06:07:53.907411 systemd-networkd[845]: eth0: Gained carrier Sep 5 06:07:53.907434 systemd-networkd[845]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 06:07:53.982796 systemd-networkd[845]: eth0: DHCPv4 address 10.0.0.56/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 5 06:07:53.993717 ignition[750]: Ignition 2.22.0 Sep 5 06:07:53.994182 ignition[750]: Stage: fetch-offline Sep 5 06:07:53.994283 ignition[750]: no configs at "/usr/lib/ignition/base.d" Sep 5 06:07:53.994304 ignition[750]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 06:07:53.994475 ignition[750]: parsed url from cmdline: "" Sep 5 06:07:53.994481 ignition[750]: no config URL provided Sep 5 06:07:53.994489 ignition[750]: reading system config file "/usr/lib/ignition/user.ign" Sep 5 06:07:53.994500 ignition[750]: no config at "/usr/lib/ignition/user.ign" Sep 5 06:07:53.994538 ignition[750]: op(1): [started] loading QEMU firmware config module Sep 5 06:07:53.994552 ignition[750]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 5 06:07:54.004281 ignition[750]: op(1): [finished] loading QEMU firmware config module Sep 5 06:07:54.004336 ignition[750]: QEMU firmware config was not found. Ignoring... Sep 5 06:07:54.006879 ignition[750]: parsing config with SHA512: 32f76361362ff50923d16aef48ceeb55c6ea86f8b46876d6e81ce4b8bc726a5e30afd786e115316dbce2ff0a0b2e443e295b134e241338e9be7d017bd6e3273c Sep 5 06:07:54.014091 unknown[750]: fetched base config from "system" Sep 5 06:07:54.014109 unknown[750]: fetched user config from "qemu" Sep 5 06:07:54.014322 ignition[750]: fetch-offline: fetch-offline passed Sep 5 06:07:54.014416 ignition[750]: Ignition finished successfully Sep 5 06:07:54.017797 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 06:07:54.019486 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 5 06:07:54.020538 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 5 06:07:54.067031 ignition[859]: Ignition 2.22.0 Sep 5 06:07:54.067050 ignition[859]: Stage: kargs Sep 5 06:07:54.067243 ignition[859]: no configs at "/usr/lib/ignition/base.d" Sep 5 06:07:54.067256 ignition[859]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 06:07:54.068018 ignition[859]: kargs: kargs passed Sep 5 06:07:54.068070 ignition[859]: Ignition finished successfully Sep 5 06:07:54.073164 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 5 06:07:54.077262 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 5 06:07:54.125717 ignition[867]: Ignition 2.22.0 Sep 5 06:07:54.125736 ignition[867]: Stage: disks Sep 5 06:07:54.125867 ignition[867]: no configs at "/usr/lib/ignition/base.d" Sep 5 06:07:54.125877 ignition[867]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 06:07:54.127432 ignition[867]: disks: disks passed Sep 5 06:07:54.128260 ignition[867]: Ignition finished successfully Sep 5 06:07:54.133539 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 5 06:07:54.134891 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 5 06:07:54.136848 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 5 06:07:54.138050 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 06:07:54.139999 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 06:07:54.140058 systemd[1]: Reached target basic.target - Basic System. Sep 5 06:07:54.141443 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 5 06:07:54.172896 systemd-fsck[877]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 5 06:07:54.181088 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 5 06:07:54.182178 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 5 06:07:54.365678 kernel: EXT4-fs (vda9): mounted filesystem 5e58259f-916a-43e8-ae75-b44bea97e14e r/w with ordered data mode. Quota mode: none. Sep 5 06:07:54.366706 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 5 06:07:54.369196 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 5 06:07:54.373007 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 06:07:54.376002 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 5 06:07:54.378284 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 5 06:07:54.378339 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 5 06:07:54.393608 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 06:07:54.403623 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 5 06:07:54.406912 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 5 06:07:54.411842 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (885) Sep 5 06:07:54.411867 kernel: BTRFS info (device vda6): first mount of filesystem b74bbc0c-6da1-4206-9f48-c70f629ccdff Sep 5 06:07:54.411882 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 06:07:54.413671 kernel: BTRFS info (device vda6): turning on async discard Sep 5 06:07:54.413697 kernel: BTRFS info (device vda6): enabling free space tree Sep 5 06:07:54.415861 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 06:07:54.447461 initrd-setup-root[909]: cut: /sysroot/etc/passwd: No such file or directory Sep 5 06:07:54.453049 initrd-setup-root[916]: cut: /sysroot/etc/group: No such file or directory Sep 5 06:07:54.458418 initrd-setup-root[923]: cut: /sysroot/etc/shadow: No such file or directory Sep 5 06:07:54.463635 initrd-setup-root[930]: cut: /sysroot/etc/gshadow: No such file or directory Sep 5 06:07:54.571886 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 5 06:07:54.574991 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 5 06:07:54.577790 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 5 06:07:54.610555 kernel: BTRFS info (device vda6): last unmount of filesystem b74bbc0c-6da1-4206-9f48-c70f629ccdff Sep 5 06:07:54.611013 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 5 06:07:54.632016 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 5 06:07:54.812458 ignition[999]: INFO : Ignition 2.22.0 Sep 5 06:07:54.812458 ignition[999]: INFO : Stage: mount Sep 5 06:07:54.814603 ignition[999]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 06:07:54.814603 ignition[999]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 06:07:54.814603 ignition[999]: INFO : mount: mount passed Sep 5 06:07:54.814603 ignition[999]: INFO : Ignition finished successfully Sep 5 06:07:54.816991 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 5 06:07:54.819271 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 5 06:07:54.865121 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 5 06:07:54.903679 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1011) Sep 5 06:07:54.906026 kernel: BTRFS info (device vda6): first mount of filesystem b74bbc0c-6da1-4206-9f48-c70f629ccdff Sep 5 06:07:54.906051 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 5 06:07:54.909681 kernel: BTRFS info (device vda6): turning on async discard Sep 5 06:07:54.909738 kernel: BTRFS info (device vda6): enabling free space tree Sep 5 06:07:54.911790 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 5 06:07:55.005091 ignition[1028]: INFO : Ignition 2.22.0 Sep 5 06:07:55.005091 ignition[1028]: INFO : Stage: files Sep 5 06:07:55.006863 ignition[1028]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 06:07:55.006863 ignition[1028]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 06:07:55.006863 ignition[1028]: DEBUG : files: compiled without relabeling support, skipping Sep 5 06:07:55.010530 ignition[1028]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 5 06:07:55.010530 ignition[1028]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 5 06:07:55.013284 ignition[1028]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 5 06:07:55.013284 ignition[1028]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 5 06:07:55.016170 ignition[1028]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 5 06:07:55.013491 unknown[1028]: wrote ssh authorized keys file for user: core Sep 5 06:07:55.172746 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 06:07:55.174704 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 5 06:07:55.174704 ignition[1028]: INFO : files: op(4): [started] processing unit "etcd-member.service" Sep 5 06:07:55.178116 ignition[1028]: 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 5 06:07:55.207137 ignition[1028]: 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 5 06:07:55.207137 ignition[1028]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Sep 5 06:07:55.207137 ignition[1028]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Sep 5 06:07:55.212764 ignition[1028]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 5 06:07:55.212764 ignition[1028]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 5 06:07:55.212764 ignition[1028]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Sep 5 06:07:55.212764 ignition[1028]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Sep 5 06:07:55.232236 ignition[1028]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 5 06:07:55.239098 ignition[1028]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 5 06:07:55.240914 ignition[1028]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Sep 5 06:07:55.240914 ignition[1028]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Sep 5 06:07:55.240914 ignition[1028]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Sep 5 06:07:55.240914 ignition[1028]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 5 06:07:55.240914 ignition[1028]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 5 06:07:55.240914 ignition[1028]: INFO : files: files passed Sep 5 06:07:55.240914 ignition[1028]: INFO : Ignition finished successfully Sep 5 06:07:55.248283 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 5 06:07:55.251006 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 5 06:07:55.253506 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 5 06:07:55.255702 systemd-networkd[845]: eth0: Gained IPv6LL Sep 5 06:07:55.263895 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 5 06:07:55.264017 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 5 06:07:55.267834 initrd-setup-root-after-ignition[1058]: grep: /sysroot/oem/oem-release: No such file or directory Sep 5 06:07:55.270851 initrd-setup-root-after-ignition[1060]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 06:07:55.272588 initrd-setup-root-after-ignition[1060]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 5 06:07:55.274099 initrd-setup-root-after-ignition[1064]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 5 06:07:55.277320 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 06:07:55.278726 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 5 06:07:55.281710 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 5 06:07:55.346700 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 5 06:07:55.346840 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 5 06:07:55.348801 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 5 06:07:55.351804 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 5 06:07:55.353798 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 5 06:07:55.354948 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 5 06:07:55.384129 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 06:07:55.386924 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 5 06:07:55.421660 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 5 06:07:55.421909 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 06:07:55.425259 systemd[1]: Stopped target timers.target - Timer Units. Sep 5 06:07:55.428070 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 5 06:07:55.428252 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 5 06:07:55.431459 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 5 06:07:55.431661 systemd[1]: Stopped target basic.target - Basic System. Sep 5 06:07:55.433577 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 5 06:07:55.435342 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 5 06:07:55.437407 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 5 06:07:55.437774 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 5 06:07:55.438312 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 5 06:07:55.438658 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 5 06:07:55.439144 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 5 06:07:55.439455 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 5 06:07:55.439944 systemd[1]: Stopped target swap.target - Swaps. Sep 5 06:07:55.440222 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 5 06:07:55.440366 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 5 06:07:55.454874 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 5 06:07:55.456075 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 06:07:55.456359 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 5 06:07:55.460415 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 06:07:55.461435 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 5 06:07:55.461601 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 5 06:07:55.465801 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 5 06:07:55.465954 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 5 06:07:55.468159 systemd[1]: Stopped target paths.target - Path Units. Sep 5 06:07:55.469203 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 5 06:07:55.474789 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 06:07:55.475017 systemd[1]: Stopped target slices.target - Slice Units. Sep 5 06:07:55.477565 systemd[1]: Stopped target sockets.target - Socket Units. Sep 5 06:07:55.478105 systemd[1]: iscsid.socket: Deactivated successfully. Sep 5 06:07:55.478199 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 5 06:07:55.481467 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 5 06:07:55.481592 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 5 06:07:55.484291 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 5 06:07:55.484439 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 5 06:07:55.485317 systemd[1]: ignition-files.service: Deactivated successfully. Sep 5 06:07:55.485446 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 5 06:07:55.490901 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 5 06:07:55.492224 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 5 06:07:55.492390 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 06:07:55.493798 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 5 06:07:55.497753 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 5 06:07:55.497998 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 06:07:55.500353 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 5 06:07:55.500500 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 5 06:07:55.507564 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 5 06:07:55.507791 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 5 06:07:55.530380 ignition[1085]: INFO : Ignition 2.22.0 Sep 5 06:07:55.530380 ignition[1085]: INFO : Stage: umount Sep 5 06:07:55.532335 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 5 06:07:55.532335 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 5 06:07:55.532335 ignition[1085]: INFO : umount: umount passed Sep 5 06:07:55.532335 ignition[1085]: INFO : Ignition finished successfully Sep 5 06:07:55.534924 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 5 06:07:55.535713 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 5 06:07:55.535862 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 5 06:07:55.538011 systemd[1]: Stopped target network.target - Network. Sep 5 06:07:55.539593 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 5 06:07:55.539688 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 5 06:07:55.541489 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 5 06:07:55.541559 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 5 06:07:55.543537 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 5 06:07:55.543603 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 5 06:07:55.544065 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 5 06:07:55.544161 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 5 06:07:55.545979 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 5 06:07:55.556181 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 5 06:07:55.556877 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 5 06:07:55.557068 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 5 06:07:55.559978 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 5 06:07:55.560144 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 5 06:07:55.565995 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 5 06:07:55.566400 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 5 06:07:55.566578 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 5 06:07:55.571802 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 5 06:07:55.573995 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 5 06:07:55.574062 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 5 06:07:55.577851 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 5 06:07:55.577946 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 5 06:07:55.582283 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 5 06:07:55.584080 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 5 06:07:55.584180 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 5 06:07:55.586426 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 5 06:07:55.586493 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 5 06:07:55.588944 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 5 06:07:55.589012 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 5 06:07:55.589819 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 5 06:07:55.589875 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 06:07:55.593869 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 06:07:55.603767 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 5 06:07:55.603932 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 5 06:07:55.610451 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 5 06:07:55.610544 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 5 06:07:55.610635 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 5 06:07:55.617848 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 5 06:07:55.618039 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 06:07:55.619146 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 5 06:07:55.619193 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 5 06:07:55.622229 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 5 06:07:55.622269 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 06:07:55.623225 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 5 06:07:55.623272 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 5 06:07:55.627128 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 5 06:07:55.627203 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 5 06:07:55.628561 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 5 06:07:55.628625 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 5 06:07:55.630722 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 5 06:07:55.634251 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 5 06:07:55.634323 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 06:07:55.638971 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 5 06:07:55.639038 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 06:07:55.643420 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 5 06:07:55.643476 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 06:07:55.648231 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 5 06:07:55.648297 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 5 06:07:55.648355 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 5 06:07:55.666837 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 5 06:07:55.666987 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 5 06:07:55.669580 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 5 06:07:55.672508 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 5 06:07:55.701255 systemd[1]: Switching root. Sep 5 06:07:55.749816 systemd-journald[220]: Journal stopped Sep 5 06:07:57.002242 systemd-journald[220]: Received SIGTERM from PID 1 (systemd). Sep 5 06:07:57.002323 kernel: SELinux: policy capability network_peer_controls=1 Sep 5 06:07:57.002349 kernel: SELinux: policy capability open_perms=1 Sep 5 06:07:57.002365 kernel: SELinux: policy capability extended_socket_class=1 Sep 5 06:07:57.002382 kernel: SELinux: policy capability always_check_network=0 Sep 5 06:07:57.002396 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 5 06:07:57.002415 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 5 06:07:57.002430 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 5 06:07:57.002456 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 5 06:07:57.002472 kernel: SELinux: policy capability userspace_initial_context=0 Sep 5 06:07:57.002488 kernel: audit: type=1403 audit(1757052475.880:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 5 06:07:57.002521 systemd[1]: Successfully loaded SELinux policy in 70.201ms. Sep 5 06:07:57.002551 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.233ms. Sep 5 06:07:57.002569 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 5 06:07:57.002595 systemd[1]: Detected virtualization kvm. Sep 5 06:07:57.002611 systemd[1]: Detected architecture x86-64. Sep 5 06:07:57.002626 systemd[1]: Detected first boot. Sep 5 06:07:57.003573 systemd[1]: Initializing machine ID from VM UUID. Sep 5 06:07:57.003600 zram_generator::config[1131]: No configuration found. Sep 5 06:07:57.003631 kernel: Guest personality initialized and is inactive Sep 5 06:07:57.003670 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 5 06:07:57.003687 kernel: Initialized host personality Sep 5 06:07:57.003702 kernel: NET: Registered PF_VSOCK protocol family Sep 5 06:07:57.003718 systemd[1]: Populated /etc with preset unit settings. Sep 5 06:07:57.003736 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 5 06:07:57.003753 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 5 06:07:57.003770 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 5 06:07:57.003786 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 5 06:07:57.003810 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 5 06:07:57.003827 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 5 06:07:57.003843 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 5 06:07:57.003873 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 5 06:07:57.003890 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 5 06:07:57.003906 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 5 06:07:57.003922 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 5 06:07:57.003938 systemd[1]: Created slice user.slice - User and Session Slice. Sep 5 06:07:57.003954 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 5 06:07:57.003974 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 5 06:07:57.003990 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 5 06:07:57.004007 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 5 06:07:57.004024 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 5 06:07:57.004040 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 5 06:07:57.004056 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 5 06:07:57.004109 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 5 06:07:57.004132 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 5 06:07:57.004148 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 5 06:07:57.004173 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 5 06:07:57.004190 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 5 06:07:57.004207 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 5 06:07:57.004223 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 5 06:07:57.004238 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 5 06:07:57.004253 systemd[1]: Reached target slices.target - Slice Units. Sep 5 06:07:57.004278 systemd[1]: Reached target swap.target - Swaps. Sep 5 06:07:57.004294 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 5 06:07:57.004313 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 5 06:07:57.004329 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 5 06:07:57.004345 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 5 06:07:57.004360 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 5 06:07:57.004374 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 5 06:07:57.004393 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 5 06:07:57.004409 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 5 06:07:57.004424 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 5 06:07:57.004440 systemd[1]: Mounting media.mount - External Media Directory... Sep 5 06:07:57.004460 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:57.004477 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 5 06:07:57.004493 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 5 06:07:57.004509 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 5 06:07:57.004540 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 5 06:07:57.004557 systemd[1]: Reached target machines.target - Containers. Sep 5 06:07:57.004572 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 5 06:07:57.004588 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 06:07:57.004609 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 5 06:07:57.004625 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 5 06:07:57.004671 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 06:07:57.004688 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 06:07:57.004704 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 06:07:57.004720 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 5 06:07:57.004736 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 06:07:57.004753 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 5 06:07:57.004773 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 5 06:07:57.004789 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 5 06:07:57.004805 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 5 06:07:57.004821 systemd[1]: Stopped systemd-fsck-usr.service. Sep 5 06:07:57.004837 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 06:07:57.004854 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 5 06:07:57.004870 kernel: fuse: init (API version 7.41) Sep 5 06:07:57.004886 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 5 06:07:57.004902 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 5 06:07:57.004922 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 5 06:07:57.004938 kernel: loop: module loaded Sep 5 06:07:57.004982 systemd-journald[1202]: Collecting audit messages is disabled. Sep 5 06:07:57.005013 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 5 06:07:57.005029 systemd-journald[1202]: Journal started Sep 5 06:07:57.005060 systemd-journald[1202]: Runtime Journal (/run/log/journal/641d8b49bf884d71893647f08eac877d) is 6M, max 48.6M, 42.5M free. Sep 5 06:07:56.704441 systemd[1]: Queued start job for default target multi-user.target. Sep 5 06:07:56.720191 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 5 06:07:56.722335 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 5 06:07:57.014095 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 5 06:07:57.014181 systemd[1]: verity-setup.service: Deactivated successfully. Sep 5 06:07:57.014204 systemd[1]: Stopped verity-setup.service. Sep 5 06:07:57.014224 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:57.019890 systemd[1]: Started systemd-journald.service - Journal Service. Sep 5 06:07:57.020888 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 5 06:07:57.022264 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 5 06:07:57.083317 systemd[1]: Mounted media.mount - External Media Directory. Sep 5 06:07:57.084693 kernel: ACPI: bus type drm_connector registered Sep 5 06:07:57.087086 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 5 06:07:57.088704 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 5 06:07:57.090235 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 5 06:07:57.091853 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 5 06:07:57.093813 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 5 06:07:57.094049 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 5 06:07:57.156044 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 06:07:57.156293 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 06:07:57.158225 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 06:07:57.158525 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 06:07:57.160347 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 06:07:57.160688 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 06:07:57.162591 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 5 06:07:57.162908 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 5 06:07:57.164682 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 06:07:57.165014 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 06:07:57.166736 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 5 06:07:57.168440 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 5 06:07:57.170350 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 5 06:07:57.172237 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 5 06:07:57.182592 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 5 06:07:57.192182 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 5 06:07:57.195175 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 5 06:07:57.197576 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 5 06:07:57.198916 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 5 06:07:57.198953 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 5 06:07:57.201467 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 5 06:07:57.215838 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 5 06:07:57.217607 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 06:07:57.219480 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 5 06:07:57.221843 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 5 06:07:57.223252 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 06:07:57.226817 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 5 06:07:57.228144 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 06:07:57.231775 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 5 06:07:57.233809 systemd-journald[1202]: Time spent on flushing to /var/log/journal/641d8b49bf884d71893647f08eac877d is 22.402ms for 956 entries. Sep 5 06:07:57.233809 systemd-journald[1202]: System Journal (/var/log/journal/641d8b49bf884d71893647f08eac877d) is 8M, max 195.6M, 187.6M free. Sep 5 06:07:57.649006 systemd-journald[1202]: Received client request to flush runtime journal. Sep 5 06:07:57.649073 kernel: loop0: detected capacity change from 0 to 128016 Sep 5 06:07:57.649110 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 5 06:07:57.234543 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 5 06:07:57.240837 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 5 06:07:57.242379 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 5 06:07:57.505606 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 5 06:07:57.545255 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 5 06:07:57.547753 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 5 06:07:57.551758 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 5 06:07:57.558940 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 5 06:07:57.565040 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 5 06:07:57.651141 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 5 06:07:57.676163 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 5 06:07:57.678168 kernel: loop1: detected capacity change from 0 to 111000 Sep 5 06:07:57.719045 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 5 06:07:57.720697 kernel: loop2: detected capacity change from 0 to 128016 Sep 5 06:07:57.725610 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 5 06:07:57.729320 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 5 06:07:57.802717 kernel: loop3: detected capacity change from 0 to 111000 Sep 5 06:07:57.816309 systemd-tmpfiles[1270]: ACLs are not supported, ignoring. Sep 5 06:07:57.816332 systemd-tmpfiles[1270]: ACLs are not supported, ignoring. Sep 5 06:07:57.820243 (sd-merge)[1268]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 5 06:07:57.821694 (sd-merge)[1268]: Merged extensions into '/usr'. Sep 5 06:07:57.823348 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 5 06:07:57.826764 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 5 06:07:57.830730 systemd[1]: Starting ensure-sysext.service... Sep 5 06:07:57.834789 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 5 06:07:57.883553 systemd[1]: Reload requested from client PID 1274 ('systemctl') (unit ensure-sysext.service)... Sep 5 06:07:57.883766 systemd[1]: Reloading... Sep 5 06:07:57.914923 systemd-tmpfiles[1275]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 5 06:07:57.914972 systemd-tmpfiles[1275]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 5 06:07:57.915383 systemd-tmpfiles[1275]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 5 06:07:57.915776 systemd-tmpfiles[1275]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 5 06:07:57.916937 systemd-tmpfiles[1275]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 5 06:07:57.917306 systemd-tmpfiles[1275]: ACLs are not supported, ignoring. Sep 5 06:07:57.917397 systemd-tmpfiles[1275]: ACLs are not supported, ignoring. Sep 5 06:07:57.926586 systemd-tmpfiles[1275]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 06:07:57.926733 systemd-tmpfiles[1275]: Skipping /boot Sep 5 06:07:57.944856 systemd-tmpfiles[1275]: Detected autofs mount point /boot during canonicalization of boot. Sep 5 06:07:57.945036 systemd-tmpfiles[1275]: Skipping /boot Sep 5 06:07:58.014696 zram_generator::config[1306]: No configuration found. Sep 5 06:07:58.072956 ldconfig[1238]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 5 06:07:58.264942 systemd[1]: Reloading finished in 380 ms. Sep 5 06:07:58.343702 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 5 06:07:58.368655 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 5 06:07:58.378380 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 5 06:07:58.394506 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 5 06:07:58.398508 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 5 06:07:58.409013 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 5 06:07:58.412951 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 5 06:07:58.419725 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 5 06:07:58.427023 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:58.427437 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 06:07:58.429362 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 06:07:58.432025 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 06:07:58.437696 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 06:07:58.439331 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 06:07:58.439498 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 06:07:58.461009 augenrules[1370]: No rules Sep 5 06:07:58.486534 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 5 06:07:58.490490 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 5 06:07:58.491801 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:58.493591 systemd[1]: audit-rules.service: Deactivated successfully. Sep 5 06:07:58.493886 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 5 06:07:58.495495 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 5 06:07:58.497625 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 5 06:07:58.499889 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 06:07:58.500127 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 06:07:58.502040 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 06:07:58.502302 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 06:07:58.504235 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 06:07:58.504466 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 06:07:58.518217 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:58.519573 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 06:07:58.521037 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 06:07:58.523501 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 06:07:58.527030 systemd-udevd[1375]: Using default interface naming scheme 'v255'. Sep 5 06:07:58.536262 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 06:07:58.537752 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 06:07:58.537939 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 06:07:58.542000 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 5 06:07:58.553724 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 5 06:07:58.553909 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:58.556618 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 5 06:07:58.566524 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 5 06:07:58.568529 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 06:07:58.571836 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 06:07:58.573516 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 5 06:07:58.575923 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 06:07:58.576223 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 06:07:58.578337 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 06:07:58.579378 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 06:07:58.584340 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 5 06:07:58.611454 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:58.613190 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 5 06:07:58.614523 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 5 06:07:58.616368 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 5 06:07:58.619034 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 5 06:07:58.651973 augenrules[1424]: /sbin/augenrules: No change Sep 5 06:07:58.671730 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 5 06:07:58.674963 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 5 06:07:58.676313 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 5 06:07:58.676364 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 5 06:07:58.679655 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 5 06:07:58.680972 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 5 06:07:58.681015 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 5 06:07:58.684073 augenrules[1446]: No rules Sep 5 06:07:58.688960 systemd[1]: Finished ensure-sysext.service. Sep 5 06:07:58.691525 systemd[1]: audit-rules.service: Deactivated successfully. Sep 5 06:07:58.691959 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 5 06:07:58.693577 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 5 06:07:58.693801 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 5 06:07:58.695792 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 5 06:07:58.696062 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 5 06:07:58.697763 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 5 06:07:58.698032 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 5 06:07:58.700146 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 5 06:07:58.700418 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 5 06:07:58.712278 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 5 06:07:58.712546 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 5 06:07:58.719927 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 5 06:07:58.721675 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 5 06:07:58.730427 systemd-resolved[1351]: Positive Trust Anchors: Sep 5 06:07:58.730447 systemd-resolved[1351]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 5 06:07:58.730507 systemd-resolved[1351]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 5 06:07:58.737349 systemd-resolved[1351]: Defaulting to hostname 'linux'. Sep 5 06:07:58.741225 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 5 06:07:58.743091 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 5 06:07:58.837834 kernel: mousedev: PS/2 mouse device common for all mice Sep 5 06:07:58.844150 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 5 06:07:58.848771 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 5 06:07:58.851177 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 5 06:07:58.857677 kernel: ACPI: button: Power Button [PWRF] Sep 5 06:07:58.874765 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 5 06:07:58.875128 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 5 06:07:58.885345 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 5 06:07:58.956094 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 5 06:07:58.959801 systemd[1]: Reached target sysinit.target - System Initialization. Sep 5 06:07:58.961263 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 5 06:07:58.962735 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 5 06:07:58.964259 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 5 06:07:58.965668 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 5 06:07:58.967218 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 5 06:07:58.967260 systemd[1]: Reached target paths.target - Path Units. Sep 5 06:07:58.968338 systemd[1]: Reached target time-set.target - System Time Set. Sep 5 06:07:58.970149 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 5 06:07:58.971577 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 5 06:07:58.973098 systemd[1]: Reached target timers.target - Timer Units. Sep 5 06:07:58.975297 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 5 06:07:58.979888 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 5 06:07:58.985111 systemd-networkd[1445]: lo: Link UP Sep 5 06:07:58.985123 systemd-networkd[1445]: lo: Gained carrier Sep 5 06:07:58.987094 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 5 06:07:58.990768 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 5 06:07:58.992355 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 5 06:07:58.997622 systemd-networkd[1445]: Enumeration completed Sep 5 06:07:59.022240 systemd-networkd[1445]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 06:07:59.022253 systemd-networkd[1445]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 5 06:07:59.022391 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 5 06:07:59.024396 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 5 06:07:59.024588 systemd-networkd[1445]: eth0: Link UP Sep 5 06:07:59.026346 systemd-networkd[1445]: eth0: Gained carrier Sep 5 06:07:59.026373 systemd-networkd[1445]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 5 06:07:59.027300 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 5 06:07:59.030173 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 5 06:07:59.033854 systemd[1]: Reached target network.target - Network. Sep 5 06:07:59.034944 systemd[1]: Reached target sockets.target - Socket Units. Sep 5 06:07:59.036179 systemd[1]: Reached target basic.target - Basic System. Sep 5 06:07:59.037547 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 5 06:07:59.037708 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 5 06:07:59.039893 systemd[1]: Starting containerd.service - containerd container runtime... Sep 5 06:07:59.041205 systemd-networkd[1445]: eth0: DHCPv4 address 10.0.0.56/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 5 06:07:59.042201 systemd-timesyncd[1457]: Network configuration changed, trying to establish connection. Sep 5 06:07:59.043941 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 5 06:08:00.032152 systemd-timesyncd[1457]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 5 06:08:00.032213 systemd-timesyncd[1457]: Initial clock synchronization to Fri 2025-09-05 06:08:00.032068 UTC. Sep 5 06:08:00.032648 systemd-resolved[1351]: Clock change detected. Flushing caches. Sep 5 06:08:00.036018 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 5 06:08:00.040333 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 5 06:08:00.045316 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 5 06:08:00.048154 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 5 06:08:00.052605 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 5 06:08:00.058270 jq[1493]: false Sep 5 06:08:00.097487 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 5 06:08:00.100285 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 5 06:08:00.103516 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 5 06:08:00.110469 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 5 06:08:00.114715 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 5 06:08:00.122065 google_oslogin_nss_cache[1495]: oslogin_cache_refresh[1495]: Refreshing passwd entry cache Sep 5 06:08:00.122087 oslogin_cache_refresh[1495]: Refreshing passwd entry cache Sep 5 06:08:00.189452 google_oslogin_nss_cache[1495]: oslogin_cache_refresh[1495]: Failure getting users, quitting Sep 5 06:08:00.189452 google_oslogin_nss_cache[1495]: oslogin_cache_refresh[1495]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 5 06:08:00.189452 google_oslogin_nss_cache[1495]: oslogin_cache_refresh[1495]: Refreshing group entry cache Sep 5 06:08:00.188859 oslogin_cache_refresh[1495]: Failure getting users, quitting Sep 5 06:08:00.188887 oslogin_cache_refresh[1495]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 5 06:08:00.188963 oslogin_cache_refresh[1495]: Refreshing group entry cache Sep 5 06:08:00.193204 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 5 06:08:00.198517 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 5 06:08:00.199654 google_oslogin_nss_cache[1495]: oslogin_cache_refresh[1495]: Failure getting groups, quitting Sep 5 06:08:00.200476 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 5 06:08:00.202660 oslogin_cache_refresh[1495]: Failure getting groups, quitting Sep 5 06:08:00.203148 google_oslogin_nss_cache[1495]: oslogin_cache_refresh[1495]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 5 06:08:00.202999 systemd[1]: Starting update-engine.service - Update Engine... Sep 5 06:08:00.202706 oslogin_cache_refresh[1495]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 5 06:08:00.213114 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 5 06:08:00.218064 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 5 06:08:00.221439 jq[1511]: true Sep 5 06:08:00.236086 extend-filesystems[1494]: Found /dev/vda6 Sep 5 06:08:00.233945 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 5 06:08:00.247919 update_engine[1509]: I20250905 06:08:00.247308 1509 main.cc:92] Flatcar Update Engine starting Sep 5 06:08:00.234249 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 5 06:08:00.234679 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 5 06:08:00.234941 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 5 06:08:00.263633 kernel: kvm_amd: TSC scaling supported Sep 5 06:08:00.263701 kernel: kvm_amd: Nested Virtualization enabled Sep 5 06:08:00.263739 kernel: kvm_amd: Nested Paging enabled Sep 5 06:08:00.263756 kernel: kvm_amd: LBR virtualization supported Sep 5 06:08:00.263773 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 5 06:08:00.263794 kernel: kvm_amd: Virtual GIF supported Sep 5 06:08:00.271637 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 5 06:08:00.279628 jq[1514]: true Sep 5 06:08:00.292009 (ntainerd)[1521]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 5 06:08:00.297542 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 5 06:08:00.317651 dbus-daemon[1490]: [system] SELinux support is enabled Sep 5 06:08:00.352224 update_engine[1509]: I20250905 06:08:00.322500 1509 update_check_scheduler.cc:74] Next update check in 4m46s Sep 5 06:08:00.392838 extend-filesystems[1494]: Found /dev/vda9 Sep 5 06:08:00.393321 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 5 06:08:00.401435 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 5 06:08:00.401492 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 5 06:08:00.402839 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 5 06:08:00.402855 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 5 06:08:00.405465 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 5 06:08:00.405758 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 5 06:08:00.411977 systemd[1]: Started update-engine.service - Update Engine. Sep 5 06:08:00.418220 extend-filesystems[1494]: Checking size of /dev/vda9 Sep 5 06:08:00.421184 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 5 06:08:00.422261 systemd-logind[1499]: Watching system buttons on /dev/input/event2 (Power Button) Sep 5 06:08:00.422283 systemd-logind[1499]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 5 06:08:00.422628 systemd-logind[1499]: New seat seat0. Sep 5 06:08:00.424896 systemd[1]: Started systemd-logind.service - User Login Management. Sep 5 06:08:00.426312 systemd[1]: motdgen.service: Deactivated successfully. Sep 5 06:08:00.427068 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 5 06:08:00.452663 extend-filesystems[1494]: Old size kept for /dev/vda9 Sep 5 06:08:00.456449 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 5 06:08:00.461074 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 5 06:08:00.534633 kernel: EDAC MC: Ver: 3.0.0 Sep 5 06:08:00.541168 locksmithd[1534]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 5 06:08:00.572187 bash[1554]: Updated "/home/core/.ssh/authorized_keys" Sep 5 06:08:00.744703 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 5 06:08:00.759455 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 5 06:08:00.773207 containerd[1521]: time="2025-09-05T06:08:00Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 5 06:08:00.774041 containerd[1521]: time="2025-09-05T06:08:00.774008394Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 5 06:08:00.783092 containerd[1521]: time="2025-09-05T06:08:00.783065743Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.084µs" Sep 5 06:08:00.783173 containerd[1521]: time="2025-09-05T06:08:00.783158838Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 5 06:08:00.783258 containerd[1521]: time="2025-09-05T06:08:00.783244769Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 5 06:08:00.783470 containerd[1521]: time="2025-09-05T06:08:00.783453570Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 5 06:08:00.783527 containerd[1521]: time="2025-09-05T06:08:00.783515837Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 5 06:08:00.783592 containerd[1521]: time="2025-09-05T06:08:00.783580629Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 5 06:08:00.783728 containerd[1521]: time="2025-09-05T06:08:00.783710522Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 5 06:08:00.783797 containerd[1521]: time="2025-09-05T06:08:00.783784070Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784080 containerd[1521]: time="2025-09-05T06:08:00.784060349Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784133 containerd[1521]: time="2025-09-05T06:08:00.784121443Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784179 containerd[1521]: time="2025-09-05T06:08:00.784167610Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784222 containerd[1521]: time="2025-09-05T06:08:00.784211632Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784370 containerd[1521]: time="2025-09-05T06:08:00.784354771Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784666 containerd[1521]: time="2025-09-05T06:08:00.784647951Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784741 containerd[1521]: time="2025-09-05T06:08:00.784727410Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 5 06:08:00.784789 containerd[1521]: time="2025-09-05T06:08:00.784777664Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 5 06:08:00.784882 containerd[1521]: time="2025-09-05T06:08:00.784867663Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 5 06:08:00.785178 containerd[1521]: time="2025-09-05T06:08:00.785160212Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 5 06:08:00.785291 containerd[1521]: time="2025-09-05T06:08:00.785274336Z" level=info msg="metadata content store policy set" policy=shared Sep 5 06:08:00.787169 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 5 06:08:00.798747 sshd_keygen[1508]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 5 06:08:00.826892 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 5 06:08:00.844038 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 5 06:08:00.872033 systemd[1]: issuegen.service: Deactivated successfully. Sep 5 06:08:00.872309 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 5 06:08:00.875064 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 5 06:08:00.904741 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 5 06:08:00.910464 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 5 06:08:00.921551 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 5 06:08:00.922873 systemd[1]: Reached target getty.target - Login Prompts. Sep 5 06:08:00.932333 containerd[1521]: time="2025-09-05T06:08:00.932266351Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 5 06:08:00.932425 containerd[1521]: time="2025-09-05T06:08:00.932385133Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 5 06:08:00.932459 containerd[1521]: time="2025-09-05T06:08:00.932428304Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 5 06:08:00.932459 containerd[1521]: time="2025-09-05T06:08:00.932444745Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 5 06:08:00.932510 containerd[1521]: time="2025-09-05T06:08:00.932458621Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 5 06:08:00.932510 containerd[1521]: time="2025-09-05T06:08:00.932474461Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 5 06:08:00.932510 containerd[1521]: time="2025-09-05T06:08:00.932500931Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 5 06:08:00.932769 containerd[1521]: time="2025-09-05T06:08:00.932553710Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 5 06:08:00.932769 containerd[1521]: time="2025-09-05T06:08:00.932572144Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 5 06:08:00.932769 containerd[1521]: time="2025-09-05T06:08:00.932587904Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 5 06:08:00.932769 containerd[1521]: time="2025-09-05T06:08:00.932641524Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 5 06:08:00.932769 containerd[1521]: time="2025-09-05T06:08:00.932664828Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 5 06:08:00.932958 containerd[1521]: time="2025-09-05T06:08:00.932872067Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 5 06:08:00.932958 containerd[1521]: time="2025-09-05T06:08:00.932906361Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 5 06:08:00.932958 containerd[1521]: time="2025-09-05T06:08:00.932924675Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 5 06:08:00.932958 containerd[1521]: time="2025-09-05T06:08:00.932937880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 5 06:08:00.932958 containerd[1521]: time="2025-09-05T06:08:00.932952798Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 5 06:08:00.933076 containerd[1521]: time="2025-09-05T06:08:00.932967255Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 5 06:08:00.933076 containerd[1521]: time="2025-09-05T06:08:00.932982664Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 5 06:08:00.933076 containerd[1521]: time="2025-09-05T06:08:00.932998734Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 5 06:08:00.933076 containerd[1521]: time="2025-09-05T06:08:00.933012560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 5 06:08:00.933076 containerd[1521]: time="2025-09-05T06:08:00.933025525Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 5 06:08:00.933076 containerd[1521]: time="2025-09-05T06:08:00.933039471Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 5 06:08:00.933245 containerd[1521]: time="2025-09-05T06:08:00.933143486Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 5 06:08:00.933245 containerd[1521]: time="2025-09-05T06:08:00.933169505Z" level=info msg="Start snapshots syncer" Sep 5 06:08:00.933245 containerd[1521]: time="2025-09-05T06:08:00.933213838Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 5 06:08:00.933669 containerd[1521]: time="2025-09-05T06:08:00.933593360Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 5 06:08:00.933849 containerd[1521]: time="2025-09-05T06:08:00.933694860Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 5 06:08:00.933849 containerd[1521]: time="2025-09-05T06:08:00.933806019Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 5 06:08:00.933969 containerd[1521]: time="2025-09-05T06:08:00.933933658Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 5 06:08:00.934004 containerd[1521]: time="2025-09-05T06:08:00.933969044Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 5 06:08:00.934004 containerd[1521]: time="2025-09-05T06:08:00.933984103Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 5 06:08:00.934004 containerd[1521]: time="2025-09-05T06:08:00.933998089Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 5 06:08:00.934094 containerd[1521]: time="2025-09-05T06:08:00.934015822Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 5 06:08:00.934094 containerd[1521]: time="2025-09-05T06:08:00.934028967Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 5 06:08:00.934094 containerd[1521]: time="2025-09-05T06:08:00.934052341Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 5 06:08:00.934094 containerd[1521]: time="2025-09-05T06:08:00.934084581Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 5 06:08:00.934191 containerd[1521]: time="2025-09-05T06:08:00.934098106Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 5 06:08:00.934191 containerd[1521]: time="2025-09-05T06:08:00.934112774Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 5 06:08:00.934191 containerd[1521]: time="2025-09-05T06:08:00.934150515Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 5 06:08:00.934191 containerd[1521]: time="2025-09-05T06:08:00.934184438Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 5 06:08:00.934191 containerd[1521]: time="2025-09-05T06:08:00.934195209Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934204526Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934216208Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934244020Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934258297Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934287712Z" level=info msg="runtime interface created" Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934295026Z" level=info msg="created NRI interface" Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934304403Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 5 06:08:00.934333 containerd[1521]: time="2025-09-05T06:08:00.934317839Z" level=info msg="Connect containerd service" Sep 5 06:08:00.934511 containerd[1521]: time="2025-09-05T06:08:00.934345941Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 5 06:08:00.935195 containerd[1521]: time="2025-09-05T06:08:00.935162914Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 5 06:08:01.040444 containerd[1521]: time="2025-09-05T06:08:01.040295973Z" level=info msg="Start subscribing containerd event" Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.040729737Z" level=info msg="Start recovering state" Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.040896440Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.040940011Z" level=info msg="Start event monitor" Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.040999222Z" level=info msg="Start cni network conf syncer for default" Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.041013740Z" level=info msg="Start streaming server" Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.041021063Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.041030301Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.041043195Z" level=info msg="runtime interface starting up..." Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.041049737Z" level=info msg="starting plugins..." Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.041072239Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 5 06:08:01.041788 containerd[1521]: time="2025-09-05T06:08:01.041242479Z" level=info msg="containerd successfully booted in 0.268546s" Sep 5 06:08:01.041444 systemd[1]: Started containerd.service - containerd container runtime. Sep 5 06:08:01.874978 systemd-networkd[1445]: eth0: Gained IPv6LL Sep 5 06:08:01.878443 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 5 06:08:01.880710 systemd[1]: Reached target network-online.target - Network is Online. Sep 5 06:08:01.936547 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 5 06:08:01.939524 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 5 06:08:01.970679 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 5 06:08:01.984806 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 5 06:08:01.985071 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 5 06:08:01.988255 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 5 06:08:01.989421 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 5 06:08:02.005065 (-wrapper)[1619]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 5 06:08:02.181257 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 5 06:08:02.205343 (dockerd)[1631]: 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 5 06:08:02.893155 dockerd[1631]: time="2025-09-05T06:08:02.893049519Z" level=info msg="Starting up" Sep 5 06:08:02.894554 dockerd[1631]: time="2025-09-05T06:08:02.894507224Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 5 06:08:02.917300 dockerd[1631]: time="2025-09-05T06:08:02.917243070Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 5 06:08:03.046950 dockerd[1631]: time="2025-09-05T06:08:03.046875232Z" level=info msg="Loading containers: start." Sep 5 06:08:03.058654 kernel: Initializing XFRM netlink socket Sep 5 06:08:03.455877 systemd-networkd[1445]: docker0: Link UP Sep 5 06:08:03.462460 dockerd[1631]: time="2025-09-05T06:08:03.462378378Z" level=info msg="Loading containers: done." Sep 5 06:08:03.493294 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1728189199-merged.mount: Deactivated successfully. Sep 5 06:08:03.494973 dockerd[1631]: time="2025-09-05T06:08:03.494909547Z" 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 5 06:08:03.495072 dockerd[1631]: time="2025-09-05T06:08:03.495057234Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 5 06:08:03.495206 dockerd[1631]: time="2025-09-05T06:08:03.495185965Z" level=info msg="Initializing buildkit" Sep 5 06:08:03.532868 dockerd[1631]: time="2025-09-05T06:08:03.532793186Z" level=info msg="Completed buildkit initialization" Sep 5 06:08:03.540069 dockerd[1631]: time="2025-09-05T06:08:03.539997619Z" level=info msg="Daemon has completed initialization" Sep 5 06:08:03.540217 dockerd[1631]: time="2025-09-05T06:08:03.540087888Z" level=info msg="API listen on /run/docker.sock" Sep 5 06:08:03.540321 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 5 06:08:03.542129 etcd-wrapper[1623]: Error response from daemon: No such container: etcd-member Sep 5 06:08:03.560789 etcd-wrapper[1831]: Error response from daemon: No such container: etcd-member Sep 5 06:08:03.677739 etcd-wrapper[1852]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Sep 5 06:08:05.015080 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 5 06:08:05.017338 systemd[1]: Started sshd@0-10.0.0.56:22-10.0.0.1:59808.service - OpenSSH per-connection server daemon (10.0.0.1:59808). Sep 5 06:08:05.098228 sshd[1865]: Accepted publickey for core from 10.0.0.1 port 59808 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:05.100178 sshd-session[1865]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:05.107760 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 5 06:08:05.110232 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 5 06:08:05.119543 systemd-logind[1499]: New session 1 of user core. Sep 5 06:08:05.138703 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 5 06:08:05.143336 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 5 06:08:05.159043 (systemd)[1870]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 5 06:08:05.161298 systemd-logind[1499]: New session c1 of user core. Sep 5 06:08:05.228068 etcd-wrapper[1852]: v3.5.16: Pulling from coreos/etcd Sep 5 06:08:05.314222 systemd[1870]: Queued start job for default target default.target. Sep 5 06:08:05.333350 systemd[1870]: Created slice app.slice - User Application Slice. Sep 5 06:08:05.333389 systemd[1870]: Reached target paths.target - Paths. Sep 5 06:08:05.333436 systemd[1870]: Reached target timers.target - Timers. Sep 5 06:08:05.335707 systemd[1870]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 5 06:08:05.365701 systemd[1870]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 5 06:08:05.365840 systemd[1870]: Reached target sockets.target - Sockets. Sep 5 06:08:05.365887 systemd[1870]: Reached target basic.target - Basic System. Sep 5 06:08:05.365928 systemd[1870]: Reached target default.target - Main User Target. Sep 5 06:08:05.365963 systemd[1870]: Startup finished in 198ms. Sep 5 06:08:05.366644 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 5 06:08:05.369906 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 5 06:08:05.435995 systemd[1]: Started sshd@1-10.0.0.56:22-10.0.0.1:59812.service - OpenSSH per-connection server daemon (10.0.0.1:59812). Sep 5 06:08:05.480500 etcd-wrapper[1852]: 804c8aba2cc6: Pulling fs layer Sep 5 06:08:05.480500 etcd-wrapper[1852]: 2ae710cd8bfe: Pulling fs layer Sep 5 06:08:05.480500 etcd-wrapper[1852]: d462aa345367: Pulling fs layer Sep 5 06:08:05.480500 etcd-wrapper[1852]: 0f8b424aa0b9: Pulling fs layer Sep 5 06:08:05.480500 etcd-wrapper[1852]: d557676654e5: Pulling fs layer Sep 5 06:08:05.480500 etcd-wrapper[1852]: c8022d07192e: Pulling fs layer Sep 5 06:08:05.480500 etcd-wrapper[1852]: d858cbc252ad: Pulling fs layer Sep 5 06:08:05.480500 etcd-wrapper[1852]: 1069fc2daed1: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: b40161cd83fc: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: 5318d93a3a65: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: 307c1adadb60: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: fbb01d9e9dc9: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: fbfea02ac3cf: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: 8c26e4bf18e2: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: 1e59a65f8816: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: ffbd4ca5f0bd: Pulling fs layer Sep 5 06:08:05.480887 etcd-wrapper[1852]: b40161cd83fc: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: fbfea02ac3cf: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: 8c26e4bf18e2: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: 1e59a65f8816: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: 5318d93a3a65: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: 307c1adadb60: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: fbb01d9e9dc9: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: ffbd4ca5f0bd: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: 0f8b424aa0b9: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: d557676654e5: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: c8022d07192e: Waiting Sep 5 06:08:05.480887 etcd-wrapper[1852]: d858cbc252ad: Waiting Sep 5 06:08:05.481435 etcd-wrapper[1852]: 1069fc2daed1: Waiting Sep 5 06:08:05.501308 sshd[1881]: Accepted publickey for core from 10.0.0.1 port 59812 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:05.503141 sshd-session[1881]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:05.508621 systemd-logind[1499]: New session 2 of user core. Sep 5 06:08:05.518839 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 5 06:08:05.575158 sshd[1884]: Connection closed by 10.0.0.1 port 59812 Sep 5 06:08:05.575558 sshd-session[1881]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:05.585682 systemd[1]: sshd@1-10.0.0.56:22-10.0.0.1:59812.service: Deactivated successfully. Sep 5 06:08:05.587964 systemd[1]: session-2.scope: Deactivated successfully. Sep 5 06:08:05.588953 systemd-logind[1499]: Session 2 logged out. Waiting for processes to exit. Sep 5 06:08:05.592094 systemd[1]: Started sshd@2-10.0.0.56:22-10.0.0.1:59814.service - OpenSSH per-connection server daemon (10.0.0.1:59814). Sep 5 06:08:05.595009 systemd-logind[1499]: Removed session 2. Sep 5 06:08:05.654174 sshd[1890]: Accepted publickey for core from 10.0.0.1 port 59814 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:05.656106 sshd-session[1890]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:05.661088 systemd-logind[1499]: New session 3 of user core. Sep 5 06:08:05.670905 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 5 06:08:05.728755 sshd[1893]: Connection closed by 10.0.0.1 port 59814 Sep 5 06:08:05.729141 sshd-session[1890]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:05.734569 systemd[1]: sshd@2-10.0.0.56:22-10.0.0.1:59814.service: Deactivated successfully. Sep 5 06:08:05.738002 systemd[1]: session-3.scope: Deactivated successfully. Sep 5 06:08:05.739066 systemd-logind[1499]: Session 3 logged out. Waiting for processes to exit. Sep 5 06:08:05.741067 systemd-logind[1499]: Removed session 3. Sep 5 06:08:05.747283 etcd-wrapper[1852]: d462aa345367: Download complete Sep 5 06:08:05.788437 etcd-wrapper[1852]: 804c8aba2cc6: Verifying Checksum Sep 5 06:08:05.788437 etcd-wrapper[1852]: 804c8aba2cc6: Download complete Sep 5 06:08:05.832471 etcd-wrapper[1852]: 2ae710cd8bfe: Download complete Sep 5 06:08:06.021760 etcd-wrapper[1852]: d557676654e5: Verifying Checksum Sep 5 06:08:06.021760 etcd-wrapper[1852]: d557676654e5: Download complete Sep 5 06:08:06.114791 etcd-wrapper[1852]: c8022d07192e: Verifying Checksum Sep 5 06:08:06.114791 etcd-wrapper[1852]: c8022d07192e: Download complete Sep 5 06:08:06.157949 etcd-wrapper[1852]: 0f8b424aa0b9: Verifying Checksum Sep 5 06:08:06.157949 etcd-wrapper[1852]: 0f8b424aa0b9: Download complete Sep 5 06:08:06.229221 etcd-wrapper[1852]: 804c8aba2cc6: Pull complete Sep 5 06:08:06.300942 systemd[1]: var-lib-docker-overlay2-dab035953c24b50294b87c59e64840b6a00a01090f91b70ce49caa854ad932b3-merged.mount: Deactivated successfully. Sep 5 06:08:06.319579 etcd-wrapper[1852]: d858cbc252ad: Verifying Checksum Sep 5 06:08:06.319579 etcd-wrapper[1852]: d858cbc252ad: Download complete Sep 5 06:08:06.347564 etcd-wrapper[1852]: 2ae710cd8bfe: Pull complete Sep 5 06:08:06.398950 etcd-wrapper[1852]: 1069fc2daed1: Download complete Sep 5 06:08:06.438031 etcd-wrapper[1852]: b40161cd83fc: Verifying Checksum Sep 5 06:08:06.438031 etcd-wrapper[1852]: b40161cd83fc: Download complete Sep 5 06:08:06.632946 etcd-wrapper[1852]: 5318d93a3a65: Download complete Sep 5 06:08:06.734330 etcd-wrapper[1852]: fbb01d9e9dc9: Verifying Checksum Sep 5 06:08:06.734330 etcd-wrapper[1852]: fbb01d9e9dc9: Download complete Sep 5 06:08:06.739511 etcd-wrapper[1852]: 307c1adadb60: Verifying Checksum Sep 5 06:08:06.739511 etcd-wrapper[1852]: 307c1adadb60: Download complete Sep 5 06:08:06.958728 etcd-wrapper[1852]: 1e59a65f8816: Verifying Checksum Sep 5 06:08:06.958728 etcd-wrapper[1852]: 1e59a65f8816: Download complete Sep 5 06:08:06.974300 systemd[1]: var-lib-docker-overlay2-50881c87240935785de6a1b123b3915e43b25edc2fcf0a1e518e88a12f2027b5-merged.mount: Deactivated successfully. Sep 5 06:08:07.151148 etcd-wrapper[1852]: fbfea02ac3cf: Verifying Checksum Sep 5 06:08:07.151148 etcd-wrapper[1852]: fbfea02ac3cf: Download complete Sep 5 06:08:07.224522 etcd-wrapper[1852]: 8c26e4bf18e2: Verifying Checksum Sep 5 06:08:07.224522 etcd-wrapper[1852]: 8c26e4bf18e2: Download complete Sep 5 06:08:07.279063 etcd-wrapper[1852]: d462aa345367: Pull complete Sep 5 06:08:07.307044 systemd[1]: var-lib-docker-overlay2-639431429f731d2897742d2741792ed42d51c3eb18df784314969fa64aafe4e8-merged.mount: Deactivated successfully. Sep 5 06:08:07.465413 etcd-wrapper[1852]: ffbd4ca5f0bd: Verifying Checksum Sep 5 06:08:07.465413 etcd-wrapper[1852]: ffbd4ca5f0bd: Download complete Sep 5 06:08:07.515210 etcd-wrapper[1852]: 0f8b424aa0b9: Pull complete Sep 5 06:08:07.551974 systemd[1]: var-lib-docker-overlay2-540b9d4b63c108227cd751a6e146d350068cc524ced0f8101a91f1aa0808e67f-merged.mount: Deactivated successfully. Sep 5 06:08:07.571369 etcd-wrapper[1852]: d557676654e5: Pull complete Sep 5 06:08:07.616272 etcd-wrapper[1852]: c8022d07192e: Pull complete Sep 5 06:08:07.649381 etcd-wrapper[1852]: d858cbc252ad: Pull complete Sep 5 06:08:07.686097 etcd-wrapper[1852]: 1069fc2daed1: Pull complete Sep 5 06:08:07.717013 etcd-wrapper[1852]: b40161cd83fc: Pull complete Sep 5 06:08:07.749951 etcd-wrapper[1852]: 5318d93a3a65: Pull complete Sep 5 06:08:07.787314 etcd-wrapper[1852]: 307c1adadb60: Pull complete Sep 5 06:08:08.061326 etcd-wrapper[1852]: fbb01d9e9dc9: Pull complete Sep 5 06:08:08.304111 systemd[1]: var-lib-docker-overlay2-7c646e262df6a2a134d7e3bf26e3965ff5564e6d0b65000a054dbb65a9cf58a3-merged.mount: Deactivated successfully. Sep 5 06:08:08.578124 systemd[1]: var-lib-docker-overlay2-2920b94bcc30a5291f0fa89c92f07f440dbe2982f943ed46aea39209180b8f37-merged.mount: Deactivated successfully. Sep 5 06:08:08.744105 etcd-wrapper[1852]: fbfea02ac3cf: Pull complete Sep 5 06:08:08.896953 systemd[1]: var-lib-docker-overlay2-b308135cc5a9c5a3a506445913d85114d86a3910ebe21f5e209acef66605e75d-merged.mount: Deactivated successfully. Sep 5 06:08:09.025327 etcd-wrapper[1852]: 8c26e4bf18e2: Pull complete Sep 5 06:08:09.070215 etcd-wrapper[1852]: 1e59a65f8816: Pull complete Sep 5 06:08:09.143892 etcd-wrapper[1852]: ffbd4ca5f0bd: Pull complete Sep 5 06:08:09.163555 etcd-wrapper[1852]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Sep 5 06:08:09.170401 etcd-wrapper[1852]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Sep 5 06:08:09.259341 containerd[1521]: time="2025-09-05T06:08:09.259241496Z" level=info msg="connecting to shim 820a701bc593f579d8a83c58eeb4e29269dcc08da10130703ef5027f3df1e099" address="unix:///run/containerd/s/43d8a4d306bb2c7073567ae71d6b6e782286655650ebc0780c960e8e14c30d82" namespace=moby protocol=ttrpc version=3 Sep 5 06:08:09.327936 systemd[1]: Started docker-820a701bc593f579d8a83c58eeb4e29269dcc08da10130703ef5027f3df1e099.scope - libcontainer container 820a701bc593f579d8a83c58eeb4e29269dcc08da10130703ef5027f3df1e099. Sep 5 06:08:09.353797 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.518292Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.518517Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"641d8b49bf884d71893647f08eac877d"} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:08:09.518549Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:08:09.518566Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:08:09.518573Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:08:09.518579Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:08:09.518699Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.518723Z","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.56:2379"]} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:08:09.518814Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 5 06:08:09.519573 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.518833Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Sep 5 06:08:09.520168 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.519648Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 5 06:08:09.520168 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.519787Z","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":"641d8b49bf884d71893647f08eac877d","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.56:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"641d8b49bf884d71893647f08eac877d=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 5 06:08:09.523682 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.523279Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.149648ms"} Sep 5 06:08:09.528188 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.527881Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Sep 5 06:08:09.528188 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.527986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Sep 5 06:08:09.528358 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.528042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Sep 5 06:08:09.528358 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.528058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Sep 5 06:08:09.528358 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.528071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Sep 5 06:08:09.528431 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.528244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 5 06:08:09.534093 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:08:09.533803Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Sep 5 06:08:09.535624 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.535367Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Sep 5 06:08:09.536531 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.536353Z","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 5 06:08:09.537688 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.537259Z","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 5 06:08:09.537990 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.537431Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Sep 5 06:08:09.537990 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.537541Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Sep 5 06:08:09.537990 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.537466Z","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 5 06:08:09.537990 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.537559Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Sep 5 06:08:09.537990 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.537783Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 5 06:08:09.538545 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.538417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 5 06:08:09.538955 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.538832Z","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 5 06:08:09.540454 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.540240Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Sep 5 06:08:09.540454 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.540261Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Sep 5 06:08:09.540563 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:09.540357Z","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.56:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 5 06:08:10.329491 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.328911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Sep 5 06:08:10.329491 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.329014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Sep 5 06:08:10.329491 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.329076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Sep 5 06:08:10.329491 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.329109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Sep 5 06:08:10.329491 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.329126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Sep 5 06:08:10.329491 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.329137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Sep 5 06:08:10.329491 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.329147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Sep 5 06:08:10.338033 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.337690Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Sep 5 06:08:10.347760 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.347361Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:641d8b49bf884d71893647f08eac877d ClientURLs:[http://10.0.0.56:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Sep 5 06:08:10.347760 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.347402Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Sep 5 06:08:10.348084 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.347590Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Sep 5 06:08:10.348084 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.347736Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Sep 5 06:08:10.348084 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.347789Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Sep 5 06:08:10.348084 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.347891Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 5 06:08:10.348084 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.347936Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Sep 5 06:08:10.348230 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 5 06:08:10.348759 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.348470Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 5 06:08:10.348793 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 5 06:08:10.348992 systemd[1]: Startup finished in 3.576s (kernel) + 5.235s (initrd) + 13.548s (userspace) = 22.360s. Sep 5 06:08:10.349561 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:08:10.349356Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Sep 5 06:08:15.752838 systemd[1]: Started sshd@3-10.0.0.56:22-10.0.0.1:59596.service - OpenSSH per-connection server daemon (10.0.0.1:59596). Sep 5 06:08:15.809298 sshd[2041]: Accepted publickey for core from 10.0.0.1 port 59596 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:15.810849 sshd-session[2041]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:15.815492 systemd-logind[1499]: New session 4 of user core. Sep 5 06:08:15.824763 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 5 06:08:15.878538 sshd[2044]: Connection closed by 10.0.0.1 port 59596 Sep 5 06:08:15.878905 sshd-session[2041]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:15.895524 systemd[1]: sshd@3-10.0.0.56:22-10.0.0.1:59596.service: Deactivated successfully. Sep 5 06:08:15.897321 systemd[1]: session-4.scope: Deactivated successfully. Sep 5 06:08:15.898070 systemd-logind[1499]: Session 4 logged out. Waiting for processes to exit. Sep 5 06:08:15.900542 systemd[1]: Started sshd@4-10.0.0.56:22-10.0.0.1:59608.service - OpenSSH per-connection server daemon (10.0.0.1:59608). Sep 5 06:08:15.901358 systemd-logind[1499]: Removed session 4. Sep 5 06:08:15.957017 sshd[2050]: Accepted publickey for core from 10.0.0.1 port 59608 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:15.958789 sshd-session[2050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:15.963524 systemd-logind[1499]: New session 5 of user core. Sep 5 06:08:15.972844 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 5 06:08:16.023059 sshd[2054]: Connection closed by 10.0.0.1 port 59608 Sep 5 06:08:16.023272 sshd-session[2050]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:16.034208 systemd[1]: sshd@4-10.0.0.56:22-10.0.0.1:59608.service: Deactivated successfully. Sep 5 06:08:16.035973 systemd[1]: session-5.scope: Deactivated successfully. Sep 5 06:08:16.036823 systemd-logind[1499]: Session 5 logged out. Waiting for processes to exit. Sep 5 06:08:16.039570 systemd[1]: Started sshd@5-10.0.0.56:22-10.0.0.1:59616.service - OpenSSH per-connection server daemon (10.0.0.1:59616). Sep 5 06:08:16.040167 systemd-logind[1499]: Removed session 5. Sep 5 06:08:16.107745 sshd[2060]: Accepted publickey for core from 10.0.0.1 port 59616 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:16.109136 sshd-session[2060]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:16.113697 systemd-logind[1499]: New session 6 of user core. Sep 5 06:08:16.123757 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 5 06:08:16.179638 sshd[2063]: Connection closed by 10.0.0.1 port 59616 Sep 5 06:08:16.179982 sshd-session[2060]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:16.193479 systemd[1]: sshd@5-10.0.0.56:22-10.0.0.1:59616.service: Deactivated successfully. Sep 5 06:08:16.195725 systemd[1]: session-6.scope: Deactivated successfully. Sep 5 06:08:16.196595 systemd-logind[1499]: Session 6 logged out. Waiting for processes to exit. Sep 5 06:08:16.199701 systemd[1]: Started sshd@6-10.0.0.56:22-10.0.0.1:59630.service - OpenSSH per-connection server daemon (10.0.0.1:59630). Sep 5 06:08:16.200335 systemd-logind[1499]: Removed session 6. Sep 5 06:08:16.262214 sshd[2069]: Accepted publickey for core from 10.0.0.1 port 59630 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:16.263867 sshd-session[2069]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:16.268034 systemd-logind[1499]: New session 7 of user core. Sep 5 06:08:16.290842 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 5 06:08:16.351703 sudo[2073]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 5 06:08:16.352020 sudo[2073]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 06:08:16.368436 sudo[2073]: pam_unix(sudo:session): session closed for user root Sep 5 06:08:16.370132 sshd[2072]: Connection closed by 10.0.0.1 port 59630 Sep 5 06:08:16.370506 sshd-session[2069]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:16.379277 systemd[1]: sshd@6-10.0.0.56:22-10.0.0.1:59630.service: Deactivated successfully. Sep 5 06:08:16.381336 systemd[1]: session-7.scope: Deactivated successfully. Sep 5 06:08:16.382146 systemd-logind[1499]: Session 7 logged out. Waiting for processes to exit. Sep 5 06:08:16.384955 systemd[1]: Started sshd@7-10.0.0.56:22-10.0.0.1:59640.service - OpenSSH per-connection server daemon (10.0.0.1:59640). Sep 5 06:08:16.385629 systemd-logind[1499]: Removed session 7. Sep 5 06:08:16.442541 sshd[2079]: Accepted publickey for core from 10.0.0.1 port 59640 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:16.443936 sshd-session[2079]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:16.448691 systemd-logind[1499]: New session 8 of user core. Sep 5 06:08:16.458790 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 5 06:08:16.514734 sudo[2084]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 5 06:08:16.515066 sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 06:08:16.522133 sudo[2084]: pam_unix(sudo:session): session closed for user root Sep 5 06:08:16.529554 sudo[2083]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 5 06:08:16.529967 sudo[2083]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 5 06:08:16.541595 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 5 06:08:16.594250 augenrules[2106]: No rules Sep 5 06:08:16.595874 systemd[1]: audit-rules.service: Deactivated successfully. Sep 5 06:08:16.596223 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 5 06:08:16.597680 sudo[2083]: pam_unix(sudo:session): session closed for user root Sep 5 06:08:16.599403 sshd[2082]: Connection closed by 10.0.0.1 port 59640 Sep 5 06:08:16.599787 sshd-session[2079]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:16.618506 systemd[1]: sshd@7-10.0.0.56:22-10.0.0.1:59640.service: Deactivated successfully. Sep 5 06:08:16.620452 systemd[1]: session-8.scope: Deactivated successfully. Sep 5 06:08:16.621322 systemd-logind[1499]: Session 8 logged out. Waiting for processes to exit. Sep 5 06:08:16.623963 systemd[1]: Started sshd@8-10.0.0.56:22-10.0.0.1:59650.service - OpenSSH per-connection server daemon (10.0.0.1:59650). Sep 5 06:08:16.624540 systemd-logind[1499]: Removed session 8. Sep 5 06:08:16.687530 sshd[2115]: Accepted publickey for core from 10.0.0.1 port 59650 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:16.689287 sshd-session[2115]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:16.694319 systemd-logind[1499]: New session 9 of user core. Sep 5 06:08:16.702791 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 5 06:08:16.754966 sshd[2118]: Connection closed by 10.0.0.1 port 59650 Sep 5 06:08:16.755540 sshd-session[2115]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:16.766274 systemd[1]: sshd@8-10.0.0.56:22-10.0.0.1:59650.service: Deactivated successfully. Sep 5 06:08:16.768424 systemd[1]: session-9.scope: Deactivated successfully. Sep 5 06:08:16.769435 systemd-logind[1499]: Session 9 logged out. Waiting for processes to exit. Sep 5 06:08:16.772875 systemd[1]: Started sshd@9-10.0.0.56:22-10.0.0.1:59654.service - OpenSSH per-connection server daemon (10.0.0.1:59654). Sep 5 06:08:16.773470 systemd-logind[1499]: Removed session 9. Sep 5 06:08:16.840205 sshd[2126]: Accepted publickey for core from 10.0.0.1 port 59654 ssh2: RSA SHA256:T5qcG59uAia43oig5RBtb+eF92ubXiITNZz/7bMi53g Sep 5 06:08:16.841749 sshd-session[2126]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 5 06:08:16.846186 systemd-logind[1499]: New session 10 of user core. Sep 5 06:08:16.865734 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 5 06:08:17.216112 sshd[2129]: Connection closed by 10.0.0.1 port 59654 Sep 5 06:08:17.216377 sshd-session[2126]: pam_unix(sshd:session): session closed for user core Sep 5 06:08:17.220787 systemd[1]: sshd@9-10.0.0.56:22-10.0.0.1:59654.service: Deactivated successfully. Sep 5 06:08:17.222672 systemd[1]: session-10.scope: Deactivated successfully. Sep 5 06:08:17.223552 systemd-logind[1499]: Session 10 logged out. Waiting for processes to exit. Sep 5 06:08:17.225371 systemd-logind[1499]: Removed session 10. Sep 5 06:08:45.994921 update_engine[1509]: I20250905 06:08:45.994778 1509 update_attempter.cc:509] Updating boot flags... Sep 5 06:09:22.189111 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:22.188316Z","caller":"traceutil/trace.go:171","msg":"trace[596862584] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"118.91512ms","start":"2025-09-05T06:09:22.069372Z","end":"2025-09-05T06:09:22.188287Z","steps":["trace[596862584] 'read index received' (duration: 118.769156ms)","trace[596862584] 'applied index is now lower than readState.Index' (duration: 145.293µs)"],"step_count":2} Sep 5 06:09:22.189111 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:22.188379Z","caller":"traceutil/trace.go:171","msg":"trace[845677992] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"119.064591ms","start":"2025-09-05T06:09:22.069305Z","end":"2025-09-05T06:09:22.188369Z","steps":["trace[845677992] 'process raft request' (duration: 118.896414ms)"],"step_count":1} Sep 5 06:09:22.189111 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:22.188497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.068869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings\" limit:1 ","response":"range_response_count:0 size:4"} Sep 5 06:09:22.189111 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:22.188534Z","caller":"traceutil/trace.go:171","msg":"trace[243693317] range","detail":"{range_begin:/registry/clusterrolebindings; range_end:; response_count:0; response_revision:114; }","duration":"119.153537ms","start":"2025-09-05T06:09:22.069368Z","end":"2025-09-05T06:09:22.188521Z","steps":["trace[243693317] 'agreement among raft nodes before linearized reading' (duration: 119.041998ms)"],"step_count":1} Sep 5 06:09:22.575376 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:22.574887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.319576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} Sep 5 06:09:22.575376 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:22.574974Z","caller":"traceutil/trace.go:171","msg":"trace[401792564] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"331.108889ms","start":"2025-09-05T06:09:22.243854Z","end":"2025-09-05T06:09:22.574963Z","steps":["trace[401792564] 'process raft request' (duration: 61.330541ms)","trace[401792564] 'compare' (duration: 269.190694ms)"],"step_count":2} Sep 5 06:09:22.575376 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:22.575012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:22.243842Z","time spent":"331.15695ms","remote":"10.0.0.64:59564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 5 06:09:22.754170 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:22.753772Z","caller":"traceutil/trace.go:171","msg":"trace[1842603190] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"114.682763ms","start":"2025-09-05T06:09:22.639070Z","end":"2025-09-05T06:09:22.753752Z","steps":["trace[1842603190] 'process raft request' (duration: 38.822704ms)","trace[1842603190] 'compare' (duration: 75.762095ms)"],"step_count":2} Sep 5 06:09:23.044151 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:23.043703Z","caller":"traceutil/trace.go:171","msg":"trace[2146545500] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"213.238723ms","start":"2025-09-05T06:09:22.830445Z","end":"2025-09-05T06:09:23.043684Z","steps":["trace[2146545500] 'process raft request' (duration: 126.081045ms)","trace[2146545500] 'compare' (duration: 87.000613ms)"],"step_count":2} Sep 5 06:09:23.252025 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:23.251673Z","caller":"traceutil/trace.go:171","msg":"trace[1086352533] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"162.613479ms","start":"2025-09-05T06:09:23.089029Z","end":"2025-09-05T06:09:23.251643Z","steps":["trace[1086352533] 'process raft request' (duration: 80.801146ms)","trace[1086352533] 'compare' (duration: 81.61352ms)"],"step_count":2} Sep 5 06:09:23.606229 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:23.605849Z","caller":"traceutil/trace.go:171","msg":"trace[1573057444] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"160.903589ms","start":"2025-09-05T06:09:23.444918Z","end":"2025-09-05T06:09:23.605821Z","steps":["trace[1573057444] 'process raft request' (duration: 64.280974ms)","trace[1573057444] 'compare' (duration: 96.51838ms)"],"step_count":2} Sep 5 06:09:23.910808 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:23.910373Z","caller":"traceutil/trace.go:171","msg":"trace[583779580] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"154.18893ms","start":"2025-09-05T06:09:23.756165Z","end":"2025-09-05T06:09:23.910354Z","steps":["trace[583779580] 'process raft request' (duration: 137.9725ms)","trace[583779580] 'compare' (duration: 16.108578ms)"],"step_count":2} Sep 5 06:09:24.060239 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:24.059802Z","caller":"traceutil/trace.go:171","msg":"trace[805164049] linearizableReadLoop","detail":"{readStateIndex:141; appliedIndex:140; }","duration":"147.249824ms","start":"2025-09-05T06:09:23.912527Z","end":"2025-09-05T06:09:24.059777Z","steps":["trace[805164049] 'read index received' (duration: 98.228479ms)","trace[805164049] 'applied index is now lower than readState.Index' (duration: 49.020854ms)"],"step_count":2} Sep 5 06:09:24.060239 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:24.059851Z","caller":"traceutil/trace.go:171","msg":"trace[2001505620] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"245.100774ms","start":"2025-09-05T06:09:23.814723Z","end":"2025-09-05T06:09:24.059824Z","steps":["trace[2001505620] 'process raft request' (duration: 196.014327ms)","trace[2001505620] 'compare' (duration: 48.944961ms)"],"step_count":2} Sep 5 06:09:24.060485 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:24.060054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.412168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 06:09:24.060485 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:24.060092Z","caller":"traceutil/trace.go:171","msg":"trace[887730121] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:0; response_revision:136; }","duration":"147.585253ms","start":"2025-09-05T06:09:23.912496Z","end":"2025-09-05T06:09:24.060081Z","steps":["trace[887730121] 'agreement among raft nodes before linearized reading' (duration: 147.35932ms)"],"step_count":1} Sep 5 06:09:24.266322 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:24.264678Z","caller":"traceutil/trace.go:171","msg":"trace[1682054442] transaction","detail":"{read_only:false; response_revision:149; number_of_response:1; }","duration":"103.593488ms","start":"2025-09-05T06:09:24.161062Z","end":"2025-09-05T06:09:24.264655Z","steps":["trace[1682054442] 'process raft request' (duration: 102.749984ms)"],"step_count":1} Sep 5 06:09:24.442228 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:24.441873Z","caller":"traceutil/trace.go:171","msg":"trace[1502148116] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"110.489047ms","start":"2025-09-05T06:09:24.331363Z","end":"2025-09-05T06:09:24.441852Z","steps":["trace[1502148116] 'process raft request' (duration: 56.633597ms)","trace[1502148116] 'compare' (duration: 53.750253ms)"],"step_count":2} Sep 5 06:09:27.730151 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:27.729585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.656958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 5 06:09:27.730151 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.729723Z","caller":"traceutil/trace.go:171","msg":"trace[1103630800] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"186.46041ms","start":"2025-09-05T06:09:27.543252Z","end":"2025-09-05T06:09:27.729713Z","steps":["trace[1103630800] 'process raft request' (duration: 186.432137ms)"],"step_count":1} Sep 5 06:09:27.730151 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.729795Z","caller":"traceutil/trace.go:171","msg":"trace[429277287] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"263.090027ms","start":"2025-09-05T06:09:27.466670Z","end":"2025-09-05T06:09:27.729760Z","steps":["trace[429277287] 'process raft request' (duration: 90.210823ms)","trace[429277287] 'compare' (duration: 172.546469ms)"],"step_count":2} Sep 5 06:09:27.824579 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.824130Z","caller":"traceutil/trace.go:171","msg":"trace[997228161] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:323; }","duration":"265.274217ms","start":"2025-09-05T06:09:27.558839Z","end":"2025-09-05T06:09:27.824113Z","steps":["trace[997228161] 'read index received' (duration: 132.615388ms)","trace[997228161] 'applied index is now lower than readState.Index' (duration: 132.658157ms)"],"step_count":2} Sep 5 06:09:27.824579 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.824210Z","caller":"traceutil/trace.go:171","msg":"trace[774280552] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"264.673199ms","start":"2025-09-05T06:09:27.559532Z","end":"2025-09-05T06:09:27.824205Z","steps":["trace[774280552] 'process raft request' (duration: 264.558263ms)"],"step_count":1} Sep 5 06:09:27.824579 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.824201Z","caller":"traceutil/trace.go:171","msg":"trace[1945320490] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"265.952981ms","start":"2025-09-05T06:09:27.558220Z","end":"2025-09-05T06:09:27.824173Z","steps":["trace[1945320490] 'process raft request' (duration: 265.784094ms)"],"step_count":1} Sep 5 06:09:27.824579 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.824195Z","caller":"traceutil/trace.go:171","msg":"trace[1317577333] transaction","detail":"{read_only:false; number_of_response:0; response_revision:317; }","duration":"265.682333ms","start":"2025-09-05T06:09:27.558479Z","end":"2025-09-05T06:09:27.824161Z","steps":["trace[1317577333] 'process raft request' (duration: 265.593316ms)"],"step_count":1} Sep 5 06:09:27.824579 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:27.824365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.471437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-localhost\" limit:1 ","response":"range_response_count:1 size:4928"} Sep 5 06:09:27.824941 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.824400Z","caller":"traceutil/trace.go:171","msg":"trace[1871655239] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-localhost; range_end:; response_count:1; response_revision:317; }","duration":"265.567598ms","start":"2025-09-05T06:09:27.558817Z","end":"2025-09-05T06:09:27.824385Z","steps":["trace[1871655239] 'agreement among raft nodes before linearized reading' (duration: 265.428085ms)"],"step_count":1} Sep 5 06:09:27.945001 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.944578Z","caller":"traceutil/trace.go:171","msg":"trace[1560918055] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:328; }","duration":"120.372596ms","start":"2025-09-05T06:09:27.824184Z","end":"2025-09-05T06:09:27.944557Z","steps":["trace[1560918055] 'read index received' (duration: 23.401573ms)","trace[1560918055] 'applied index is now lower than readState.Index' (duration: 96.970251ms)"],"step_count":2} Sep 5 06:09:27.945001 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.944629Z","caller":"traceutil/trace.go:171","msg":"trace[14668110] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"197.815828ms","start":"2025-09-05T06:09:27.746783Z","end":"2025-09-05T06:09:27.944599Z","steps":["trace[14668110] 'process raft request' (duration: 100.859924ms)","trace[14668110] 'compare' (duration: 96.752983ms)"],"step_count":2} Sep 5 06:09:27.945001 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:27.944725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.907212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 06:09:27.945001 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.944746Z","caller":"traceutil/trace.go:171","msg":"trace[534948985] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:318; }","duration":"211.966173ms","start":"2025-09-05T06:09:27.732774Z","end":"2025-09-05T06:09:27.944740Z","steps":["trace[534948985] 'agreement among raft nodes before linearized reading' (duration: 211.897764ms)"],"step_count":1} Sep 5 06:09:27.951119 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:27.950681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.223872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-localhost\" limit:1 ","response":"range_response_count:1 size:6284"} Sep 5 06:09:27.951119 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.950700Z","caller":"traceutil/trace.go:171","msg":"trace[53073731] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"101.67371ms","start":"2025-09-05T06:09:27.849014Z","end":"2025-09-05T06:09:27.950688Z","steps":["trace[53073731] 'process raft request' (duration: 101.528698ms)"],"step_count":1} Sep 5 06:09:27.951119 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.950715Z","caller":"traceutil/trace.go:171","msg":"trace[2011249269] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-localhost; range_end:; response_count:1; response_revision:319; }","duration":"125.293412ms","start":"2025-09-05T06:09:27.825416Z","end":"2025-09-05T06:09:27.950709Z","steps":["trace[2011249269] 'agreement among raft nodes before linearized reading' (duration: 125.223261ms)"],"step_count":1} Sep 5 06:09:27.951119 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:27.950756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.310535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-localhost\" limit:1 ","response":"range_response_count:1 size:3151"} Sep 5 06:09:27.951119 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.950767Z","caller":"traceutil/trace.go:171","msg":"trace[1207106951] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-localhost; range_end:; response_count:1; response_revision:319; }","duration":"125.342255ms","start":"2025-09-05T06:09:27.825421Z","end":"2025-09-05T06:09:27.950763Z","steps":["trace[1207106951] 'agreement among raft nodes before linearized reading' (duration: 125.320934ms)"],"step_count":1} Sep 5 06:09:27.951385 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:27.950755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.268736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-localhost\" limit:1 ","response":"range_response_count:1 size:4968"} Sep 5 06:09:27.951385 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:27.950832Z","caller":"traceutil/trace.go:171","msg":"trace[1494600668] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-localhost; range_end:; response_count:1; response_revision:319; }","duration":"125.368073ms","start":"2025-09-05T06:09:27.825443Z","end":"2025-09-05T06:09:27.950811Z","steps":["trace[1494600668] 'agreement among raft nodes before linearized reading' (duration: 125.199626ms)"],"step_count":1} Sep 5 06:09:30.024506 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:30.024118Z","caller":"traceutil/trace.go:171","msg":"trace[1361094712] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"205.522896ms","start":"2025-09-05T06:09:29.818572Z","end":"2025-09-05T06:09:30.024095Z","steps":["trace[1361094712] 'process raft request' (duration: 159.638868ms)","trace[1361094712] 'compare' (duration: 45.783428ms)"],"step_count":2} Sep 5 06:09:30.087542 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:30.086930Z","caller":"traceutil/trace.go:171","msg":"trace[1096072480] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"221.322808ms","start":"2025-09-05T06:09:29.865585Z","end":"2025-09-05T06:09:30.086908Z","steps":["trace[1096072480] 'read index received' (duration: 112.637913ms)","trace[1096072480] 'applied index is now lower than readState.Index' (duration: 108.684154ms)"],"step_count":2} Sep 5 06:09:30.087542 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:30.086985Z","caller":"traceutil/trace.go:171","msg":"trace[1547589483] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"262.572288ms","start":"2025-09-05T06:09:29.824399Z","end":"2025-09-05T06:09:30.086971Z","steps":["trace[1547589483] 'process raft request' (duration: 262.389224ms)"],"step_count":1} Sep 5 06:09:30.087542 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:30.087056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.452601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" limit:1 ","response":"range_response_count:1 size:209"} Sep 5 06:09:30.087542 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:30.087081Z","caller":"traceutil/trace.go:171","msg":"trace[1261495147] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"221.524215ms","start":"2025-09-05T06:09:29.865550Z","end":"2025-09-05T06:09:30.087074Z","steps":["trace[1261495147] 'agreement among raft nodes before linearized reading' (duration: 221.462319ms)"],"step_count":1} Sep 5 06:09:30.087542 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:30.087123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.790947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" limit:1 ","response":"range_response_count:1 size:214"} Sep 5 06:09:30.087834 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:30.087151Z","caller":"traceutil/trace.go:171","msg":"trace[1975827130] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"121.841161ms","start":"2025-09-05T06:09:29.965303Z","end":"2025-09-05T06:09:30.087144Z","steps":["trace[1975827130] 'agreement among raft nodes before linearized reading' (duration: 121.804312ms)"],"step_count":1} Sep 5 06:09:30.087834 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:30.087095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.632875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" limit:1 ","response":"range_response_count:1 size:207"} Sep 5 06:09:30.087834 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:30.087183Z","caller":"traceutil/trace.go:171","msg":"trace[1635174429] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:348; }","duration":"171.747231ms","start":"2025-09-05T06:09:29.915431Z","end":"2025-09-05T06:09:30.087179Z","steps":["trace[1635174429] 'agreement among raft nodes before linearized reading' (duration: 171.638006ms)"],"step_count":1} Sep 5 06:09:37.949456 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:37.948981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.680167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 5 06:09:37.950070 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:37.949133Z","caller":"traceutil/trace.go:171","msg":"trace[1227878124] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"373.83928ms","start":"2025-09-05T06:09:37.575282Z","end":"2025-09-05T06:09:37.949121Z","steps":["trace[1227878124] 'read index received' (duration: 27.093µs)","trace[1227878124] 'applied index is now lower than readState.Index' (duration: 373.811476ms)"],"step_count":2} Sep 5 06:09:37.950070 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:37.949222Z","caller":"traceutil/trace.go:171","msg":"trace[1209257684] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"470.501596ms","start":"2025-09-05T06:09:37.478682Z","end":"2025-09-05T06:09:37.949184Z","steps":["trace[1209257684] 'process raft request' (duration: 470.392816ms)"],"step_count":1} Sep 5 06:09:37.950070 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:37.949263Z","caller":"traceutil/trace.go:171","msg":"trace[2106557643] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"478.187831ms","start":"2025-09-05T06:09:37.471060Z","end":"2025-09-05T06:09:37.949248Z","steps":["trace[2106557643] 'process raft request' (duration: 63.051961ms)","trace[2106557643] 'compare' (duration: 414.569523ms)"],"step_count":2} Sep 5 06:09:37.950070 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:37.949276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.995012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-localhost.18624e0018c337c7\" limit:1 ","response":"range_response_count:1 size:817"} Sep 5 06:09:37.950194 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:37.949298Z","caller":"traceutil/trace.go:171","msg":"trace[315711731] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-localhost.18624e0018c337c7; range_end:; response_count:1; response_revision:531; }","duration":"374.04608ms","start":"2025-09-05T06:09:37.575245Z","end":"2025-09-05T06:09:37.949291Z","steps":["trace[315711731] 'agreement among raft nodes before linearized reading' (duration: 373.942219ms)"],"step_count":1} Sep 5 06:09:37.950194 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:37.949321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:37.575226Z","time spent":"374.085697ms","remote":"10.0.0.64:59284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":841,"request content":"key:\"/registry/events/kube-system/kube-apiserver-localhost.18624e0018c337c7\" limit:1 "} Sep 5 06:09:37.950194 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:37.949321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:37.471048Z","time spent":"478.24424ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5238,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:37.950270 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:37.949380Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:37.478646Z","time spent":"470.647719ms","remote":"10.0.0.64:59392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6336,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:38.030377 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.029779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.213236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 06:09:38.030377 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.029933Z","caller":"traceutil/trace.go:171","msg":"trace[615744115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"269.406511ms","start":"2025-09-05T06:09:37.760507Z","end":"2025-09-05T06:09:38.029914Z","steps":["trace[615744115] 'agreement among raft nodes before linearized reading' (duration: 269.190813ms)"],"step_count":1} Sep 5 06:09:38.030653 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.029789Z","caller":"traceutil/trace.go:171","msg":"trace[63997962] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"413.946852ms","start":"2025-09-05T06:09:37.615810Z","end":"2025-09-05T06:09:38.029757Z","steps":["trace[63997962] 'process raft request' (duration: 413.168907ms)"],"step_count":1} Sep 5 06:09:38.030653 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.030212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:37.615788Z","time spent":"414.370701ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":630489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:38.220167 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.219621Z","caller":"traceutil/trace.go:171","msg":"trace[1389448519] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"268.910397ms","start":"2025-09-05T06:09:37.950679Z","end":"2025-09-05T06:09:38.219590Z","steps":["trace[1389448519] 'process raft request' (duration: 245.273994ms)","trace[1389448519] 'compare' (duration: 23.458198ms)"],"step_count":2} Sep 5 06:09:38.220167 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.219663Z","caller":"traceutil/trace.go:171","msg":"trace[1580494198] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"258.439562ms","start":"2025-09-05T06:09:37.961211Z","end":"2025-09-05T06:09:38.219651Z","steps":["trace[1580494198] 'process raft request' (duration: 258.322295ms)"],"step_count":1} Sep 5 06:09:38.220167 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.219698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.177679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 06:09:38.220167 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.219625Z","caller":"traceutil/trace.go:171","msg":"trace[467546586] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:546; }","duration":"188.090459ms","start":"2025-09-05T06:09:38.031496Z","end":"2025-09-05T06:09:38.219587Z","steps":["trace[467546586] 'read index received' (duration: 164.491589ms)","trace[467546586] 'applied index is now lower than readState.Index' (duration: 23.598139ms)"],"step_count":2} Sep 5 06:09:38.220167 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.219737Z","caller":"traceutil/trace.go:171","msg":"trace[372368573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"188.261331ms","start":"2025-09-05T06:09:38.031468Z","end":"2025-09-05T06:09:38.219729Z","steps":["trace[372368573] 'agreement among raft nodes before linearized reading' (duration: 188.190914ms)"],"step_count":1} Sep 5 06:09:38.236880 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.236494Z","caller":"traceutil/trace.go:171","msg":"trace[2134326558] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"172.87494ms","start":"2025-09-05T06:09:38.063595Z","end":"2025-09-05T06:09:38.236470Z","steps":["trace[2134326558] 'process raft request' (duration: 172.704751ms)"],"step_count":1} Sep 5 06:09:38.564551 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.564044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.627566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 5 06:09:38.564551 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.564131Z","caller":"traceutil/trace.go:171","msg":"trace[540978331] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"327.630473ms","start":"2025-09-05T06:09:38.236484Z","end":"2025-09-05T06:09:38.564114Z","steps":["trace[540978331] 'process raft request' (duration: 144.873367ms)","trace[540978331] 'compare' (duration: 182.483577ms)"],"step_count":2} Sep 5 06:09:38.564551 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.564190Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.236461Z","time spent":"327.696009ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":61120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:38.614989 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.614421Z","caller":"traceutil/trace.go:171","msg":"trace[1560878988] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:549; }","duration":"367.103527ms","start":"2025-09-05T06:09:38.247303Z","end":"2025-09-05T06:09:38.614406Z","steps":["trace[1560878988] 'read index received' (duration: 134.037444ms)","trace[1560878988] 'applied index is now lower than readState.Index' (duration: 233.065412ms)"],"step_count":2} Sep 5 06:09:38.614989 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.614467Z","caller":"traceutil/trace.go:171","msg":"trace[1833774821] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"372.913562ms","start":"2025-09-05T06:09:38.241541Z","end":"2025-09-05T06:09:38.614454Z","steps":["trace[1833774821] 'process raft request' (duration: 372.756679ms)"],"step_count":1} Sep 5 06:09:38.614989 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.614536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.227138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/apiservers.operator.tigera.io\" limit:1 ","response":"range_response_count:1 size:61135"} Sep 5 06:09:38.614989 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.614554Z","caller":"traceutil/trace.go:171","msg":"trace[908806712] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/apiservers.operator.tigera.io; range_end:; response_count:1; response_revision:538; }","duration":"367.274608ms","start":"2025-09-05T06:09:38.247273Z","end":"2025-09-05T06:09:38.614548Z","steps":["trace[908806712] 'agreement among raft nodes before linearized reading' (duration: 367.215765ms)"],"step_count":1} Sep 5 06:09:38.615247 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.614553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.241523Z","time spent":"372.973558ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5247,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:38.615247 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.614569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.247259Z","time spent":"367.305398ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":61159,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/apiservers.operator.tigera.io\" limit:1 "} Sep 5 06:09:38.615247 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.614481Z","caller":"traceutil/trace.go:171","msg":"trace[199397247] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"357.362933ms","start":"2025-09-05T06:09:38.257114Z","end":"2025-09-05T06:09:38.614477Z","steps":["trace[199397247] 'process raft request' (duration: 357.266106ms)"],"step_count":1} Sep 5 06:09:38.615459 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.614717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.257094Z","time spent":"357.553671ms","remote":"10.0.0.64:59466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:38.716769 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.716227Z","caller":"traceutil/trace.go:171","msg":"trace[1836909741] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"101.746005ms","start":"2025-09-05T06:09:38.614462Z","end":"2025-09-05T06:09:38.716208Z","steps":["trace[1836909741] 'read index received' (duration: 97.450279ms)","trace[1836909741] 'applied index is now lower than readState.Index' (duration: 4.295316ms)"],"step_count":2} Sep 5 06:09:38.716769 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.716268Z","caller":"traceutil/trace.go:171","msg":"trace[1413398953] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"122.380986ms","start":"2025-09-05T06:09:38.593873Z","end":"2025-09-05T06:09:38.716254Z","steps":["trace[1413398953] 'process raft request' (duration: 118.110609ms)"],"step_count":1} Sep 5 06:09:38.716769 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.716375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.796542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-localhost.18624e0018c337c7\" limit:1 ","response":"range_response_count:1 size:817"} Sep 5 06:09:38.716769 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.716399Z","caller":"traceutil/trace.go:171","msg":"trace[1445157788] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-localhost.18624e0018c337c7; range_end:; response_count:1; response_revision:539; }","duration":"137.848733ms","start":"2025-09-05T06:09:38.578542Z","end":"2025-09-05T06:09:38.716391Z","steps":["trace[1445157788] 'agreement among raft nodes before linearized reading' (duration: 137.745223ms)"],"step_count":1} Sep 5 06:09:38.716769 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.716429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.903198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/tigera-operator/tigera-operator-755d956888-ppdrd\" limit:1 ","response":"range_response_count:1 size:3968"} Sep 5 06:09:38.717082 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.716466Z","caller":"traceutil/trace.go:171","msg":"trace[1776282991] range","detail":"{range_begin:/registry/pods/tigera-operator/tigera-operator-755d956888-ppdrd; range_end:; response_count:1; response_revision:539; }","duration":"137.963425ms","start":"2025-09-05T06:09:38.578494Z","end":"2025-09-05T06:09:38.716458Z","steps":["trace[1776282991] 'agreement among raft nodes before linearized reading' (duration: 137.905053ms)"],"step_count":1} Sep 5 06:09:38.942959 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.942507Z","caller":"traceutil/trace.go:171","msg":"trace[410745948] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"226.210198ms","start":"2025-09-05T06:09:38.716274Z","end":"2025-09-05T06:09:38.942484Z","steps":["trace[410745948] 'read index received' (duration: 154.665842ms)","trace[410745948] 'applied index is now lower than readState.Index' (duration: 71.543775ms)"],"step_count":2} Sep 5 06:09:38.942959 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.942561Z","caller":"traceutil/trace.go:171","msg":"trace[1012396040] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"310.746247ms","start":"2025-09-05T06:09:38.631779Z","end":"2025-09-05T06:09:38.942525Z","steps":["trace[1012396040] 'process raft request' (duration: 239.144389ms)","trace[1012396040] 'compare' (duration: 71.46893ms)"],"step_count":2} Sep 5 06:09:38.943193 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.942730Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.387763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/apiservers.operator.tigera.io\" limit:1 ","response":"range_response_count:1 size:61135"} Sep 5 06:09:38.943193 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:38.942871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.631759Z","time spent":"311.061466ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56995,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:38.943193 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:38.942904Z","caller":"traceutil/trace.go:171","msg":"trace[2128867420] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/apiservers.operator.tigera.io; range_end:; response_count:1; response_revision:540; }","duration":"260.624702ms","start":"2025-09-05T06:09:38.682264Z","end":"2025-09-05T06:09:38.942889Z","steps":["trace[2128867420] 'agreement among raft nodes before linearized reading' (duration: 260.287911ms)"],"step_count":1} Sep 5 06:09:39.257148 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.256642Z","caller":"traceutil/trace.go:171","msg":"trace[1176305587] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"534.705522ms","start":"2025-09-05T06:09:38.721926Z","end":"2025-09-05T06:09:39.256632Z","steps":["trace[1176305587] 'process raft request' (duration: 534.581763ms)"],"step_count":1} Sep 5 06:09:39.257148 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.256810Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.721906Z","time spent":"534.843388ms","remote":"10.0.0.64:59392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4249,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:39.257148 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.256628Z","caller":"traceutil/trace.go:171","msg":"trace[1044614522] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"522.376011ms","start":"2025-09-05T06:09:38.734213Z","end":"2025-09-05T06:09:39.256589Z","steps":["trace[1044614522] 'process raft request' (duration: 522.332166ms)"],"step_count":1} Sep 5 06:09:39.257813 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.256870Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.734196Z","time spent":"522.658907ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56765,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:39.257813 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.256669Z","caller":"traceutil/trace.go:171","msg":"trace[1365848688] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"538.782355ms","start":"2025-09-05T06:09:38.717864Z","end":"2025-09-05T06:09:39.256656Z","steps":["trace[1365848688] 'process raft request' (duration: 533.338367ms)"],"step_count":1} Sep 5 06:09:39.257813 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.257052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.717845Z","time spent":"539.187187ms","remote":"10.0.0.64:59284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":802,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:39.558478 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.558066Z","caller":"traceutil/trace.go:171","msg":"trace[186858836] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"597.695595ms","start":"2025-09-05T06:09:38.960351Z","end":"2025-09-05T06:09:39.558046Z","steps":["trace[186858836] 'process raft request' (duration: 575.636217ms)","trace[186858836] 'compare' (duration: 21.978111ms)"],"step_count":2} Sep 5 06:09:39.558478 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.558183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:38.960323Z","time spent":"597.797892ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":61129,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:39.859435 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.858698Z","caller":"traceutil/trace.go:171","msg":"trace[815635352] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"597.056677ms","start":"2025-09-05T06:09:39.261601Z","end":"2025-09-05T06:09:39.858657Z","steps":["trace[815635352] 'process raft request' (duration: 533.35189ms)","trace[815635352] 'compare' (duration: 63.48877ms)"],"step_count":2} Sep 5 06:09:39.859435 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.858851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.261586Z","time spent":"597.203841ms","remote":"10.0.0.64:59284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 5 06:09:39.859435 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.858704Z","caller":"traceutil/trace.go:171","msg":"trace[1039671179] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"595.364197ms","start":"2025-09-05T06:09:39.263328Z","end":"2025-09-05T06:09:39.858692Z","steps":["trace[1039671179] 'process raft request' (duration: 595.227433ms)"],"step_count":1} Sep 5 06:09:39.859698 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.858985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.263301Z","time spent":"595.618098ms","remote":"10.0.0.64:59680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:39.859698 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.858717Z","caller":"traceutil/trace.go:171","msg":"trace[1325220890] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"531.627898ms","start":"2025-09-05T06:09:39.327069Z","end":"2025-09-05T06:09:39.858697Z","steps":["trace[1325220890] 'process raft request' (duration: 531.589484ms)"],"step_count":1} Sep 5 06:09:39.859698 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.859072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.327046Z","time spent":"531.989559ms","remote":"10.0.0.64:59466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:39.859822 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:39.858728Z","caller":"traceutil/trace.go:171","msg":"trace[153523481] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"594.979614ms","start":"2025-09-05T06:09:39.263737Z","end":"2025-09-05T06:09:39.858717Z","steps":["trace[153523481] 'process raft request' (duration: 594.860383ms)"],"step_count":1} Sep 5 06:09:39.859822 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:39.859152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.263729Z","time spent":"595.396469ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4466,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:40.063661 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.063107Z","caller":"traceutil/trace.go:171","msg":"trace[1091119697] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:558; }","duration":"302.168023ms","start":"2025-09-05T06:09:39.760920Z","end":"2025-09-05T06:09:40.063088Z","steps":["trace[1091119697] 'read index received' (duration: 34.101383ms)","trace[1091119697] 'applied index is now lower than readState.Index' (duration: 268.065638ms)"],"step_count":2} Sep 5 06:09:40.063661 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.063146Z","caller":"traceutil/trace.go:171","msg":"trace[2016244021] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"461.208506ms","start":"2025-09-05T06:09:39.601922Z","end":"2025-09-05T06:09:40.063131Z","steps":["trace[2016244021] 'process raft request' (duration: 434.614508ms)","trace[2016244021] 'compare' (duration: 26.353564ms)"],"step_count":2} Sep 5 06:09:40.063661 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.063214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.285429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 06:09:40.063661 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.063236Z","caller":"traceutil/trace.go:171","msg":"trace[800472601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"302.34758ms","start":"2025-09-05T06:09:39.760882Z","end":"2025-09-05T06:09:40.063230Z","steps":["trace[800472601] 'agreement among raft nodes before linearized reading' (duration: 302.292784ms)"],"step_count":1} Sep 5 06:09:40.063953 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.063245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.601907Z","time spent":"461.273111ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":138239,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:40.063953 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.063256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.760861Z","time spent":"302.388418ms","remote":"10.0.0.64:59168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Sep 5 06:09:40.419114 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.418722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.472411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 5 06:09:40.419114 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.418822Z","caller":"traceutil/trace.go:171","msg":"trace[520284910] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"355.653437ms","start":"2025-09-05T06:09:40.063157Z","end":"2025-09-05T06:09:40.418810Z","steps":["trace[520284910] 'read index received' (duration: 106.04306ms)","trace[520284910] 'applied index is now lower than readState.Index' (duration: 249.609234ms)"],"step_count":2} Sep 5 06:09:40.419114 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.418885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.460409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/tigera-operator/tigera-operator-755d956888-ppdrd.18624e0204936274\" limit:1 ","response":"range_response_count:1 size:793"} Sep 5 06:09:40.419114 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.418902Z","caller":"traceutil/trace.go:171","msg":"trace[640584144] range","detail":"{range_begin:/registry/events/tigera-operator/tigera-operator-755d956888-ppdrd.18624e0204936274; range_end:; response_count:1; response_revision:550; }","duration":"545.499473ms","start":"2025-09-05T06:09:39.873393Z","end":"2025-09-05T06:09:40.418893Z","steps":["trace[640584144] 'agreement among raft nodes before linearized reading' (duration: 545.463233ms)"],"step_count":1} Sep 5 06:09:40.419955 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.418893Z","caller":"traceutil/trace.go:171","msg":"trace[469780606] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"543.953838ms","start":"2025-09-05T06:09:39.874905Z","end":"2025-09-05T06:09:40.418859Z","steps":["trace[469780606] 'process raft request' (duration: 294.284657ms)","trace[469780606] 'compare' (duration: 249.316389ms)"],"step_count":2} Sep 5 06:09:40.419955 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.418925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.873380Z","time spent":"545.537748ms","remote":"10.0.0.64:59284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":817,"request content":"key:\"/registry/events/tigera-operator/tigera-operator-755d956888-ppdrd.18624e0204936274\" limit:1 "} Sep 5 06:09:40.419955 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.419047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:39.874885Z","time spent":"544.073319ms","remote":"10.0.0.64:59644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3256,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:40.429038 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.428530Z","caller":"traceutil/trace.go:171","msg":"trace[1866521533] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"342.71542ms","start":"2025-09-05T06:09:40.085793Z","end":"2025-09-05T06:09:40.428508Z","steps":["trace[1866521533] 'process raft request' (duration: 342.488592ms)"],"step_count":1} Sep 5 06:09:40.429038 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.428686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:40.085740Z","time spent":"342.856572ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56774,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:40.491471 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.491043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.555348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 5 06:09:40.491471 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.491117Z","caller":"traceutil/trace.go:171","msg":"trace[1024857326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"426.668406ms","start":"2025-09-05T06:09:40.064435Z","end":"2025-09-05T06:09:40.491103Z","steps":["trace[1024857326] 'agreement among raft nodes before linearized reading' (duration: 426.540109ms)"],"step_count":1} Sep 5 06:09:40.491471 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.491148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:40.064421Z","time spent":"426.719845ms","remote":"10.0.0.64:59160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} Sep 5 06:09:40.850572 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.849945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.225578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 5 06:09:40.850572 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.850138Z","caller":"traceutil/trace.go:171","msg":"trace[1505185087] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:569; }","duration":"221.08994ms","start":"2025-09-05T06:09:40.629037Z","end":"2025-09-05T06:09:40.850127Z","steps":["trace[1505185087] 'read index received' (duration: 55.636563ms)","trace[1505185087] 'applied index is now lower than readState.Index' (duration: 165.452806ms)"],"step_count":2} Sep 5 06:09:40.850572 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:40.850215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.173752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 06:09:40.850572 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.850308Z","caller":"traceutil/trace.go:171","msg":"trace[1427663259] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions; range_end:; response_count:0; response_revision:556; }","duration":"221.277793ms","start":"2025-09-05T06:09:40.629015Z","end":"2025-09-05T06:09:40.850293Z","steps":["trace[1427663259] 'agreement among raft nodes before linearized reading' (duration: 221.17751ms)"],"step_count":1} Sep 5 06:09:40.850906 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.850240Z","caller":"traceutil/trace.go:171","msg":"trace[1850186502] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"251.918033ms","start":"2025-09-05T06:09:40.598301Z","end":"2025-09-05T06:09:40.850219Z","steps":["trace[1850186502] 'process raft request' (duration: 86.365483ms)","trace[1850186502] 'compare' (duration: 165.120295ms)"],"step_count":2} Sep 5 06:09:40.850906 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:40.850249Z","caller":"traceutil/trace.go:171","msg":"trace[1630294786] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"229.640387ms","start":"2025-09-05T06:09:40.620594Z","end":"2025-09-05T06:09:40.850235Z","steps":["trace[1630294786] 'process raft request' (duration: 229.498473ms)"],"step_count":1} Sep 5 06:09:41.184927 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.184515Z","caller":"traceutil/trace.go:171","msg":"trace[601776328] transaction","detail":"{read_only:false; number_of_response:0; response_revision:556; }","duration":"378.172571ms","start":"2025-09-05T06:09:40.806321Z","end":"2025-09-05T06:09:41.184494Z","steps":["trace[601776328] 'process raft request' (duration: 348.888675ms)","trace[601776328] 'compare' (duration: 29.230153ms)"],"step_count":2} Sep 5 06:09:41.185123 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.184665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:40.806300Z","time spent":"378.27548ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} Sep 5 06:09:41.251039 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.250510Z","caller":"traceutil/trace.go:171","msg":"trace[199379432] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"187.521037ms","start":"2025-09-05T06:09:41.062972Z","end":"2025-09-05T06:09:41.250493Z","steps":["trace[199379432] 'read index received' (duration: 92.249433ms)","trace[199379432] 'applied index is now lower than readState.Index' (duration: 95.270913ms)"],"step_count":2} Sep 5 06:09:41.251039 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.250655Z","caller":"traceutil/trace.go:171","msg":"trace[48871710] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"396.29654ms","start":"2025-09-05T06:09:40.854326Z","end":"2025-09-05T06:09:41.250622Z","steps":["trace[48871710] 'process raft request' (duration: 396.051808ms)"],"step_count":1} Sep 5 06:09:41.251349 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.250821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:40.854303Z","time spent":"396.420589ms","remote":"10.0.0.64:59680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3075,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:41.251349 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.250693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.698871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/\" range_end:\"/registry/operator.tigera.io/installations0\" limit:10000 ","response":"range_response_count:0 size:5"} Sep 5 06:09:41.251349 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.250905Z","caller":"traceutil/trace.go:171","msg":"trace[602236263] range","detail":"{range_begin:/registry/operator.tigera.io/installations/; range_end:/registry/operator.tigera.io/installations0; response_count:0; response_revision:557; }","duration":"187.953923ms","start":"2025-09-05T06:09:41.062941Z","end":"2025-09-05T06:09:41.250895Z","steps":["trace[602236263] 'agreement among raft nodes before linearized reading' (duration: 187.652752ms)"],"step_count":1} Sep 5 06:09:41.251349 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.250972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.783313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 06:09:41.251523 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.251001Z","caller":"traceutil/trace.go:171","msg":"trace[1682503785] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:0; response_revision:557; }","duration":"187.955605ms","start":"2025-09-05T06:09:41.063038Z","end":"2025-09-05T06:09:41.250994Z","steps":["trace[1682503785] 'agreement among raft nodes before linearized reading' (duration: 187.805085ms)"],"step_count":1} Sep 5 06:09:41.251523 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.251035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.999551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/\" range_end:\"/registry/operator.tigera.io/installations0\" count_only:true ","response":"range_response_count:0 size:5"} Sep 5 06:09:41.251523 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.251093Z","caller":"traceutil/trace.go:171","msg":"trace[2068098004] range","detail":"{range_begin:/registry/operator.tigera.io/installations/; range_end:/registry/operator.tigera.io/installations0; response_count:0; response_revision:557; }","duration":"188.111818ms","start":"2025-09-05T06:09:41.062966Z","end":"2025-09-05T06:09:41.251078Z","steps":["trace[2068098004] 'agreement among raft nodes before linearized reading' (duration: 187.727155ms)"],"step_count":1} Sep 5 06:09:41.541344 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.540950Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.112077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 5 06:09:41.541344 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.541024Z","caller":"traceutil/trace.go:171","msg":"trace[1770055528] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"285.649758ms","start":"2025-09-05T06:09:41.255362Z","end":"2025-09-05T06:09:41.541012Z","steps":["trace[1770055528] 'process raft request' (duration: 79.421978ms)","trace[1770055528] 'compare' (duration: 205.994571ms)"],"step_count":2} Sep 5 06:09:41.774393 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.773813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.605115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} Sep 5 06:09:41.774393 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.773985Z","caller":"traceutil/trace.go:171","msg":"trace[461946063] transaction","detail":"{read_only:false; number_of_response:0; response_revision:558; }","duration":"517.944379ms","start":"2025-09-05T06:09:41.256001Z","end":"2025-09-05T06:09:41.773946Z","steps":["trace[461946063] 'process raft request' (duration: 412.152072ms)","trace[461946063] 'compare' (duration: 105.558346ms)"],"step_count":2} Sep 5 06:09:41.774393 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.774043Z","caller":"traceutil/trace.go:171","msg":"trace[788140014] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"225.373731ms","start":"2025-09-05T06:09:41.548657Z","end":"2025-09-05T06:09:41.774030Z","steps":["trace[788140014] 'read index received' (duration: 119.484266ms)","trace[788140014] 'applied index is now lower than readState.Index' (duration: 105.888894ms)"],"step_count":2} Sep 5 06:09:41.774393 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.774059Z","caller":"traceutil/trace.go:171","msg":"trace[552626526] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"517.688216ms","start":"2025-09-05T06:09:41.256359Z","end":"2025-09-05T06:09:41.774047Z","steps":["trace[552626526] 'process raft request' (duration: 517.540951ms)"],"step_count":1} Sep 5 06:09:41.775386 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.774120Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.461662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/apiservers/\" range_end:\"/registry/operator.tigera.io/apiservers0\" limit:10000 ","response":"range_response_count:0 size:5"} Sep 5 06:09:41.775386 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.774124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:41.256350Z","time spent":"517.738483ms","remote":"10.0.0.64:59644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3262,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 5 06:09:41.775386 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.774125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-05T06:09:41.255983Z","time spent":"518.081695ms","remote":"10.0.0.64:59240","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} Sep 5 06:09:41.775482 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.774139Z","caller":"traceutil/trace.go:171","msg":"trace[1749361446] range","detail":"{range_begin:/registry/operator.tigera.io/apiservers/; range_end:/registry/operator.tigera.io/apiservers0; response_count:0; response_revision:559; }","duration":"225.500075ms","start":"2025-09-05T06:09:41.548634Z","end":"2025-09-05T06:09:41.774134Z","steps":["trace[1749361446] 'agreement among raft nodes before linearized reading' (duration: 225.456191ms)"],"step_count":1} Sep 5 06:09:41.775482 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.774222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.565232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/apiservers/\" range_end:\"/registry/operator.tigera.io/apiservers0\" count_only:true ","response":"range_response_count:0 size:5"} Sep 5 06:09:41.775482 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.774235Z","caller":"traceutil/trace.go:171","msg":"trace[1675824137] range","detail":"{range_begin:/registry/operator.tigera.io/apiservers/; range_end:/registry/operator.tigera.io/apiservers0; response_count:0; response_revision:559; }","duration":"225.602022ms","start":"2025-09-05T06:09:41.548629Z","end":"2025-09-05T06:09:41.774231Z","steps":["trace[1675824137] 'agreement among raft nodes before linearized reading' (duration: 225.582524ms)"],"step_count":1} Sep 5 06:09:41.775482 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:41.774243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.503101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/apiservers/default\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 06:09:41.775603 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:41.774311Z","caller":"traceutil/trace.go:171","msg":"trace[1368661312] range","detail":"{range_begin:/registry/operator.tigera.io/apiservers/default; range_end:; response_count:0; response_revision:559; }","duration":"225.581922ms","start":"2025-09-05T06:09:41.548712Z","end":"2025-09-05T06:09:41.774294Z","steps":["trace[1368661312] 'agreement among raft nodes before linearized reading' (duration: 225.500746ms)"],"step_count":1} Sep 5 06:09:42.031703 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.031308Z","caller":"traceutil/trace.go:171","msg":"trace[475552630] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"217.593431ms","start":"2025-09-05T06:09:41.813697Z","end":"2025-09-05T06:09:42.031291Z","steps":["trace[475552630] 'process raft request' (duration: 217.529839ms)"],"step_count":1} Sep 5 06:09:42.031703 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.031320Z","caller":"traceutil/trace.go:171","msg":"trace[1049872142] transaction","detail":"{read_only:false; number_of_response:0; response_revision:560; }","duration":"253.549538ms","start":"2025-09-05T06:09:41.777759Z","end":"2025-09-05T06:09:42.031309Z","steps":["trace[1049872142] 'process raft request' (duration: 253.402385ms)"],"step_count":1} Sep 5 06:09:42.035070 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.034467Z","caller":"traceutil/trace.go:171","msg":"trace[1505954365] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"161.16433ms","start":"2025-09-05T06:09:41.873292Z","end":"2025-09-05T06:09:42.034456Z","steps":["trace[1505954365] 'process raft request' (duration: 161.020422ms)"],"step_count":1} Sep 5 06:09:42.035070 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.034446Z","caller":"traceutil/trace.go:171","msg":"trace[1352481194] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:578; }","duration":"154.359723ms","start":"2025-09-05T06:09:41.880065Z","end":"2025-09-05T06:09:42.034425Z","steps":["trace[1352481194] 'read index received' (duration: 151.167309ms)","trace[1352481194] 'applied index is now lower than readState.Index' (duration: 3.191242ms)"],"step_count":2} Sep 5 06:09:42.035070 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:42.034539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.459987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/whiskers/\" range_end:\"/registry/operator.tigera.io/whiskers0\" limit:10000 ","response":"range_response_count:0 size:5"} Sep 5 06:09:42.035070 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.034557Z","caller":"traceutil/trace.go:171","msg":"trace[788731347] range","detail":"{range_begin:/registry/operator.tigera.io/whiskers/; range_end:/registry/operator.tigera.io/whiskers0; response_count:0; response_revision:562; }","duration":"154.515755ms","start":"2025-09-05T06:09:41.880036Z","end":"2025-09-05T06:09:42.034552Z","steps":["trace[788731347] 'agreement among raft nodes before linearized reading' (duration: 154.473603ms)"],"step_count":1} Sep 5 06:09:42.035316 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:42.034638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.466169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/whiskers/\" range_end:\"/registry/operator.tigera.io/whiskers0\" count_only:true ","response":"range_response_count:0 size:5"} Sep 5 06:09:42.035316 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.034684Z","caller":"traceutil/trace.go:171","msg":"trace[299429249] range","detail":"{range_begin:/registry/operator.tigera.io/whiskers/; range_end:/registry/operator.tigera.io/whiskers0; response_count:0; response_revision:562; }","duration":"154.561583ms","start":"2025-09-05T06:09:41.880099Z","end":"2025-09-05T06:09:42.034661Z","steps":["trace[299429249] 'agreement among raft nodes before linearized reading' (duration: 154.462592ms)"],"step_count":1} Sep 5 06:09:42.035316 etcd-wrapper[1852]: {"level":"warn","ts":"2025-09-05T06:09:42.034770Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.406103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/whiskers/default\" limit:1 ","response":"range_response_count:0 size:5"} Sep 5 06:09:42.035316 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.034870Z","caller":"traceutil/trace.go:171","msg":"trace[1184616043] range","detail":"{range_begin:/registry/operator.tigera.io/whiskers/default; range_end:; response_count:0; response_revision:562; }","duration":"154.564078ms","start":"2025-09-05T06:09:41.880288Z","end":"2025-09-05T06:09:42.034852Z","steps":["trace[1184616043] 'agreement among raft nodes before linearized reading' (duration: 154.298396ms)"],"step_count":1} Sep 5 06:09:42.422098 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.421603Z","caller":"traceutil/trace.go:171","msg":"trace[1397383844] transaction","detail":"{read_only:false; number_of_response:0; response_revision:563; }","duration":"220.792622ms","start":"2025-09-05T06:09:42.200793Z","end":"2025-09-05T06:09:42.421585Z","steps":["trace[1397383844] 'process raft request' (duration: 220.728489ms)"],"step_count":1} Sep 5 06:09:42.586987 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.586631Z","caller":"traceutil/trace.go:171","msg":"trace[1842787058] transaction","detail":"{read_only:false; number_of_response:0; response_revision:563; }","duration":"153.155905ms","start":"2025-09-05T06:09:42.433439Z","end":"2025-09-05T06:09:42.586595Z","steps":["trace[1842787058] 'process raft request' (duration: 153.084417ms)"],"step_count":1} Sep 5 06:09:42.960314 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:42.959858Z","caller":"traceutil/trace.go:171","msg":"trace[62114430] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"104.692603ms","start":"2025-09-05T06:09:42.855130Z","end":"2025-09-05T06:09:42.959823Z","steps":["trace[62114430] 'process raft request' (duration: 104.534158ms)"],"step_count":1} Sep 5 06:09:43.195122 etcd-wrapper[1852]: {"level":"info","ts":"2025-09-05T06:09:43.194691Z","caller":"traceutil/trace.go:171","msg":"trace[1970551449] transaction","detail":"{read_only:false; number_of_response:0; response_revision:564; }","duration":"135.243599ms","start":"2025-09-05T06:09:43.059426Z","end":"2025-09-05T06:09:43.194669Z","steps":["trace[1970551449] 'process raft request' (duration: 135.141311ms)"],"step_count":1}