May 17 00:16:18.865818 kernel: Linux version 6.6.90-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Fri May 16 22:44:56 -00 2025 May 17 00:16:18.865838 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=6b60288baeea1613a76a6f06a8f0e8edc178eae4857ce00eac42d48e92ed015e May 17 00:16:18.865848 kernel: BIOS-provided physical RAM map: May 17 00:16:18.865855 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 17 00:16:18.865861 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 17 00:16:18.865867 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 17 00:16:18.865874 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable May 17 00:16:18.865880 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved May 17 00:16:18.865886 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved May 17 00:16:18.865895 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved May 17 00:16:18.865901 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 17 00:16:18.865907 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 17 00:16:18.865913 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 17 00:16:18.865920 kernel: NX (Execute Disable) protection: active May 17 00:16:18.865932 kernel: APIC: Static calls initialized May 17 00:16:18.865941 kernel: SMBIOS 2.8 present. May 17 00:16:18.865948 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 May 17 00:16:18.865955 kernel: Hypervisor detected: KVM May 17 00:16:18.865961 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 17 00:16:18.865968 kernel: kvm-clock: using sched offset of 2210715379 cycles May 17 00:16:18.865975 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 17 00:16:18.865982 kernel: tsc: Detected 2794.748 MHz processor May 17 00:16:18.865989 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 17 00:16:18.865997 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 17 00:16:18.866003 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 May 17 00:16:18.866012 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs May 17 00:16:18.866019 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 17 00:16:18.866033 kernel: Using GB pages for direct mapping May 17 00:16:18.866040 kernel: ACPI: Early table checksum verification disabled May 17 00:16:18.866047 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) May 17 00:16:18.866054 kernel: ACPI: RSDT 0x000000009CFE2408 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 17 00:16:18.866061 kernel: ACPI: FACP 0x000000009CFE21E8 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 17 00:16:18.866068 kernel: ACPI: DSDT 0x000000009CFE0040 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 17 00:16:18.866077 kernel: ACPI: FACS 0x000000009CFE0000 000040 May 17 00:16:18.866084 kernel: ACPI: APIC 0x000000009CFE22DC 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 17 00:16:18.866091 kernel: ACPI: HPET 0x000000009CFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 17 00:16:18.866097 kernel: ACPI: MCFG 0x000000009CFE23A4 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 17 00:16:18.866104 kernel: ACPI: WAET 0x000000009CFE23E0 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 17 00:16:18.866111 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21e8-0x9cfe22db] May 17 00:16:18.866118 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21e7] May 17 00:16:18.866128 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] May 17 00:16:18.866138 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22dc-0x9cfe236b] May 17 00:16:18.866145 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe236c-0x9cfe23a3] May 17 00:16:18.866241 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23a4-0x9cfe23df] May 17 00:16:18.866248 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23e0-0x9cfe2407] May 17 00:16:18.866255 kernel: No NUMA configuration found May 17 00:16:18.866263 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] May 17 00:16:18.866270 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] May 17 00:16:18.866280 kernel: Zone ranges: May 17 00:16:18.866287 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 17 00:16:18.866294 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] May 17 00:16:18.866301 kernel: Normal empty May 17 00:16:18.866308 kernel: Movable zone start for each node May 17 00:16:18.866315 kernel: Early memory node ranges May 17 00:16:18.866322 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 17 00:16:18.866329 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] May 17 00:16:18.866336 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] May 17 00:16:18.866345 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 17 00:16:18.866352 kernel: On node 0, zone DMA: 97 pages in unavailable ranges May 17 00:16:18.866359 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges May 17 00:16:18.866366 kernel: ACPI: PM-Timer IO Port: 0x608 May 17 00:16:18.866373 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 17 00:16:18.866381 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 17 00:16:18.866388 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 17 00:16:18.866395 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 17 00:16:18.866402 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 17 00:16:18.866411 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 17 00:16:18.866418 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 17 00:16:18.866425 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 17 00:16:18.866432 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 17 00:16:18.866439 kernel: TSC deadline timer available May 17 00:16:18.866446 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 17 00:16:18.866454 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 17 00:16:18.866461 kernel: kvm-guest: KVM setup pv remote TLB flush May 17 00:16:18.866468 kernel: kvm-guest: setup PV sched yield May 17 00:16:18.866475 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices May 17 00:16:18.866484 kernel: Booting paravirtualized kernel on KVM May 17 00:16:18.866491 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 17 00:16:18.866499 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 17 00:16:18.866506 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 May 17 00:16:18.866513 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 May 17 00:16:18.866520 kernel: pcpu-alloc: [0] 0 1 2 3 May 17 00:16:18.866527 kernel: kvm-guest: PV spinlocks enabled May 17 00:16:18.866534 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 17 00:16:18.866542 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=6b60288baeea1613a76a6f06a8f0e8edc178eae4857ce00eac42d48e92ed015e May 17 00:16:18.866552 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 17 00:16:18.866559 kernel: random: crng init done May 17 00:16:18.866566 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 17 00:16:18.866574 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 17 00:16:18.866581 kernel: Fallback order for Node 0: 0 May 17 00:16:18.866588 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 May 17 00:16:18.866595 kernel: Policy zone: DMA32 May 17 00:16:18.866602 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 17 00:16:18.866612 kernel: Memory: 2434592K/2571752K available (12288K kernel code, 2295K rwdata, 22740K rodata, 42872K init, 2320K bss, 136900K reserved, 0K cma-reserved) May 17 00:16:18.866619 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 17 00:16:18.866626 kernel: ftrace: allocating 37948 entries in 149 pages May 17 00:16:18.866633 kernel: ftrace: allocated 149 pages with 4 groups May 17 00:16:18.866640 kernel: Dynamic Preempt: voluntary May 17 00:16:18.866647 kernel: rcu: Preemptible hierarchical RCU implementation. May 17 00:16:18.866655 kernel: rcu: RCU event tracing is enabled. May 17 00:16:18.866662 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 17 00:16:18.866670 kernel: Trampoline variant of Tasks RCU enabled. May 17 00:16:18.866679 kernel: Rude variant of Tasks RCU enabled. May 17 00:16:18.866686 kernel: Tracing variant of Tasks RCU enabled. May 17 00:16:18.866693 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 17 00:16:18.866700 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 17 00:16:18.866707 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 May 17 00:16:18.866719 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 17 00:16:18.866726 kernel: Console: colour VGA+ 80x25 May 17 00:16:18.866733 kernel: printk: console [ttyS0] enabled May 17 00:16:18.866740 kernel: ACPI: Core revision 20230628 May 17 00:16:18.866750 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 17 00:16:18.866757 kernel: APIC: Switch to symmetric I/O mode setup May 17 00:16:18.866764 kernel: x2apic enabled May 17 00:16:18.866771 kernel: APIC: Switched APIC routing to: physical x2apic May 17 00:16:18.866778 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() May 17 00:16:18.866786 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() May 17 00:16:18.866793 kernel: kvm-guest: setup PV IPIs May 17 00:16:18.866809 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 17 00:16:18.866817 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized May 17 00:16:18.866824 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) May 17 00:16:18.866832 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 17 00:16:18.866839 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 May 17 00:16:18.866848 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 May 17 00:16:18.866856 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 17 00:16:18.866864 kernel: Spectre V2 : Mitigation: Retpolines May 17 00:16:18.866871 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 17 00:16:18.866879 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls May 17 00:16:18.866888 kernel: RETBleed: Mitigation: untrained return thunk May 17 00:16:18.866896 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 17 00:16:18.866903 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 17 00:16:18.866911 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! May 17 00:16:18.866919 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. May 17 00:16:18.866926 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode May 17 00:16:18.866934 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 17 00:16:18.866941 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 17 00:16:18.866951 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 17 00:16:18.866959 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 17 00:16:18.866966 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. May 17 00:16:18.866974 kernel: Freeing SMP alternatives memory: 32K May 17 00:16:18.866981 kernel: pid_max: default: 32768 minimum: 301 May 17 00:16:18.866988 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 17 00:16:18.866996 kernel: landlock: Up and running. May 17 00:16:18.867003 kernel: SELinux: Initializing. May 17 00:16:18.867011 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 17 00:16:18.867021 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 17 00:16:18.867034 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) May 17 00:16:18.867042 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 17 00:16:18.867049 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 17 00:16:18.867057 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 17 00:16:18.867064 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. May 17 00:16:18.867072 kernel: ... version: 0 May 17 00:16:18.867079 kernel: ... bit width: 48 May 17 00:16:18.867091 kernel: ... generic registers: 6 May 17 00:16:18.867100 kernel: ... value mask: 0000ffffffffffff May 17 00:16:18.867108 kernel: ... max period: 00007fffffffffff May 17 00:16:18.867115 kernel: ... fixed-purpose events: 0 May 17 00:16:18.867123 kernel: ... event mask: 000000000000003f May 17 00:16:18.867130 kernel: signal: max sigframe size: 1776 May 17 00:16:18.867137 kernel: rcu: Hierarchical SRCU implementation. May 17 00:16:18.867157 kernel: rcu: Max phase no-delay instances is 400. May 17 00:16:18.867165 kernel: smp: Bringing up secondary CPUs ... May 17 00:16:18.867172 kernel: smpboot: x86: Booting SMP configuration: May 17 00:16:18.867182 kernel: .... node #0, CPUs: #1 #2 #3 May 17 00:16:18.867190 kernel: smp: Brought up 1 node, 4 CPUs May 17 00:16:18.867197 kernel: smpboot: Max logical packages: 1 May 17 00:16:18.867205 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) May 17 00:16:18.867212 kernel: devtmpfs: initialized May 17 00:16:18.867220 kernel: x86/mm: Memory block size: 128MB May 17 00:16:18.867227 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 17 00:16:18.867235 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 17 00:16:18.867242 kernel: pinctrl core: initialized pinctrl subsystem May 17 00:16:18.867252 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 17 00:16:18.867259 kernel: audit: initializing netlink subsys (disabled) May 17 00:16:18.867267 kernel: audit: type=2000 audit(1747440978.655:1): state=initialized audit_enabled=0 res=1 May 17 00:16:18.867274 kernel: thermal_sys: Registered thermal governor 'step_wise' May 17 00:16:18.867281 kernel: thermal_sys: Registered thermal governor 'user_space' May 17 00:16:18.867289 kernel: cpuidle: using governor menu May 17 00:16:18.867296 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 17 00:16:18.867304 kernel: dca service started, version 1.12.1 May 17 00:16:18.867311 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) May 17 00:16:18.867321 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry May 17 00:16:18.867329 kernel: PCI: Using configuration type 1 for base access May 17 00:16:18.867336 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 17 00:16:18.867344 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 17 00:16:18.867352 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 17 00:16:18.867359 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 17 00:16:18.867367 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 17 00:16:18.867374 kernel: ACPI: Added _OSI(Module Device) May 17 00:16:18.867381 kernel: ACPI: Added _OSI(Processor Device) May 17 00:16:18.867391 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 17 00:16:18.867398 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 17 00:16:18.867406 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 17 00:16:18.867413 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 17 00:16:18.867421 kernel: ACPI: Interpreter enabled May 17 00:16:18.867428 kernel: ACPI: PM: (supports S0 S3 S5) May 17 00:16:18.867435 kernel: ACPI: Using IOAPIC for interrupt routing May 17 00:16:18.867443 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 17 00:16:18.867451 kernel: PCI: Using E820 reservations for host bridge windows May 17 00:16:18.867460 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F May 17 00:16:18.867467 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 17 00:16:18.867648 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 17 00:16:18.867774 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] May 17 00:16:18.867893 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] May 17 00:16:18.867902 kernel: PCI host bridge to bus 0000:00 May 17 00:16:18.868024 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 17 00:16:18.868167 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 17 00:16:18.868280 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 17 00:16:18.868389 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] May 17 00:16:18.868498 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] May 17 00:16:18.868608 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] May 17 00:16:18.868717 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 17 00:16:18.868857 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 May 17 00:16:18.868994 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 May 17 00:16:18.869124 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] May 17 00:16:18.869260 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] May 17 00:16:18.869380 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] May 17 00:16:18.869499 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 17 00:16:18.869627 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 May 17 00:16:18.869752 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] May 17 00:16:18.869872 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] May 17 00:16:18.869992 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] May 17 00:16:18.870138 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 May 17 00:16:18.870275 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] May 17 00:16:18.870395 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] May 17 00:16:18.870513 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] May 17 00:16:18.870654 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 17 00:16:18.870773 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] May 17 00:16:18.870936 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] May 17 00:16:18.871118 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] May 17 00:16:18.871258 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] May 17 00:16:18.871388 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 May 17 00:16:18.871507 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO May 17 00:16:18.871962 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 May 17 00:16:18.872094 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] May 17 00:16:18.872271 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] May 17 00:16:18.872449 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 May 17 00:16:18.872601 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] May 17 00:16:18.872612 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 17 00:16:18.872620 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 17 00:16:18.872641 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 17 00:16:18.872649 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 17 00:16:18.872664 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 May 17 00:16:18.872672 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 May 17 00:16:18.872679 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 May 17 00:16:18.872687 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 May 17 00:16:18.872694 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 May 17 00:16:18.872701 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 May 17 00:16:18.872710 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 May 17 00:16:18.872720 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 May 17 00:16:18.872727 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 May 17 00:16:18.872735 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 May 17 00:16:18.872742 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 May 17 00:16:18.872750 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 May 17 00:16:18.872757 kernel: iommu: Default domain type: Translated May 17 00:16:18.872765 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 17 00:16:18.872772 kernel: PCI: Using ACPI for IRQ routing May 17 00:16:18.872780 kernel: PCI: pci_cache_line_size set to 64 bytes May 17 00:16:18.872789 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 17 00:16:18.872797 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] May 17 00:16:18.872922 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device May 17 00:16:18.873050 kernel: pci 0000:00:01.0: vgaarb: bridge control possible May 17 00:16:18.873184 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 17 00:16:18.873195 kernel: vgaarb: loaded May 17 00:16:18.873203 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 17 00:16:18.873210 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 17 00:16:18.873221 kernel: clocksource: Switched to clocksource kvm-clock May 17 00:16:18.873229 kernel: VFS: Disk quotas dquot_6.6.0 May 17 00:16:18.873237 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 17 00:16:18.873245 kernel: pnp: PnP ACPI init May 17 00:16:18.873376 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved May 17 00:16:18.873387 kernel: pnp: PnP ACPI: found 6 devices May 17 00:16:18.873395 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 17 00:16:18.873403 kernel: NET: Registered PF_INET protocol family May 17 00:16:18.873413 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 17 00:16:18.873421 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 17 00:16:18.873429 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 17 00:16:18.873437 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 17 00:16:18.873444 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 17 00:16:18.873452 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 17 00:16:18.873460 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 17 00:16:18.873467 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 17 00:16:18.873475 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 17 00:16:18.873485 kernel: NET: Registered PF_XDP protocol family May 17 00:16:18.873597 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 17 00:16:18.873708 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 17 00:16:18.873817 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 17 00:16:18.873986 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] May 17 00:16:18.874115 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] May 17 00:16:18.874285 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] May 17 00:16:18.874297 kernel: PCI: CLS 0 bytes, default 64 May 17 00:16:18.874309 kernel: Initialise system trusted keyrings May 17 00:16:18.874317 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 17 00:16:18.874324 kernel: Key type asymmetric registered May 17 00:16:18.874332 kernel: Asymmetric key parser 'x509' registered May 17 00:16:18.874339 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 17 00:16:18.874347 kernel: io scheduler mq-deadline registered May 17 00:16:18.874354 kernel: io scheduler kyber registered May 17 00:16:18.874362 kernel: io scheduler bfq registered May 17 00:16:18.874369 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 17 00:16:18.874380 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 May 17 00:16:18.874387 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 May 17 00:16:18.874395 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 May 17 00:16:18.874402 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 17 00:16:18.874410 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 17 00:16:18.874418 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 17 00:16:18.874425 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 17 00:16:18.874433 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 17 00:16:18.874563 kernel: rtc_cmos 00:04: RTC can wake from S4 May 17 00:16:18.874577 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 17 00:16:18.874689 kernel: rtc_cmos 00:04: registered as rtc0 May 17 00:16:18.874802 kernel: rtc_cmos 00:04: setting system clock to 2025-05-17T00:16:18 UTC (1747440978) May 17 00:16:18.874970 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs May 17 00:16:18.874981 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled May 17 00:16:18.874989 kernel: NET: Registered PF_INET6 protocol family May 17 00:16:18.874997 kernel: Segment Routing with IPv6 May 17 00:16:18.875004 kernel: In-situ OAM (IOAM) with IPv6 May 17 00:16:18.875015 kernel: NET: Registered PF_PACKET protocol family May 17 00:16:18.875022 kernel: Key type dns_resolver registered May 17 00:16:18.875037 kernel: IPI shorthand broadcast: enabled May 17 00:16:18.875045 kernel: sched_clock: Marking stable (543002939, 104754454)->(695878970, -48121577) May 17 00:16:18.875052 kernel: registered taskstats version 1 May 17 00:16:18.875060 kernel: Loading compiled-in X.509 certificates May 17 00:16:18.875068 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.90-flatcar: 85b8d1234ceca483cb3defc2030d93f7792663c9' May 17 00:16:18.875075 kernel: Key type .fscrypt registered May 17 00:16:18.875083 kernel: Key type fscrypt-provisioning registered May 17 00:16:18.875093 kernel: ima: No TPM chip found, activating TPM-bypass! May 17 00:16:18.875100 kernel: ima: Allocated hash algorithm: sha1 May 17 00:16:18.875108 kernel: ima: No architecture policies found May 17 00:16:18.875116 kernel: clk: Disabling unused clocks May 17 00:16:18.875123 kernel: Freeing unused kernel image (initmem) memory: 42872K May 17 00:16:18.875131 kernel: Write protecting the kernel read-only data: 36864k May 17 00:16:18.875138 kernel: Freeing unused kernel image (rodata/data gap) memory: 1836K May 17 00:16:18.875158 kernel: Run /init as init process May 17 00:16:18.875166 kernel: with arguments: May 17 00:16:18.875176 kernel: /init May 17 00:16:18.875184 kernel: with environment: May 17 00:16:18.875191 kernel: HOME=/ May 17 00:16:18.875198 kernel: TERM=linux May 17 00:16:18.875206 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 17 00:16:18.875215 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 17 00:16:18.875225 systemd[1]: Detected virtualization kvm. May 17 00:16:18.875233 systemd[1]: Detected architecture x86-64. May 17 00:16:18.875243 systemd[1]: Running in initrd. May 17 00:16:18.875251 systemd[1]: No hostname configured, using default hostname. May 17 00:16:18.875259 systemd[1]: Hostname set to . May 17 00:16:18.875267 systemd[1]: Initializing machine ID from VM UUID. May 17 00:16:18.875275 systemd[1]: Queued start job for default target initrd.target. May 17 00:16:18.875283 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 17 00:16:18.875291 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 17 00:16:18.875299 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 17 00:16:18.875310 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 17 00:16:18.875329 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 17 00:16:18.875340 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 17 00:16:18.875349 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 17 00:16:18.875360 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 17 00:16:18.875368 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 17 00:16:18.875376 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 17 00:16:18.875385 systemd[1]: Reached target paths.target - Path Units. May 17 00:16:18.875393 systemd[1]: Reached target slices.target - Slice Units. May 17 00:16:18.875401 systemd[1]: Reached target swap.target - Swaps. May 17 00:16:18.875409 systemd[1]: Reached target timers.target - Timer Units. May 17 00:16:18.875417 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 17 00:16:18.875425 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 17 00:16:18.875436 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 17 00:16:18.875444 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 17 00:16:18.875453 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 17 00:16:18.875461 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 17 00:16:18.875469 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 17 00:16:18.875477 systemd[1]: Reached target sockets.target - Socket Units. May 17 00:16:18.875488 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 17 00:16:18.875496 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 17 00:16:18.875506 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 17 00:16:18.875514 systemd[1]: Starting systemd-fsck-usr.service... May 17 00:16:18.875523 systemd[1]: Starting systemd-journald.service - Journal Service... May 17 00:16:18.875531 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 17 00:16:18.875539 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 17 00:16:18.875548 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 17 00:16:18.875556 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 17 00:16:18.875564 systemd[1]: Finished systemd-fsck-usr.service. May 17 00:16:18.875592 systemd-journald[191]: Collecting audit messages is disabled. May 17 00:16:18.875613 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 17 00:16:18.875622 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 17 00:16:18.875633 systemd-journald[191]: Journal started May 17 00:16:18.875653 systemd-journald[191]: Runtime Journal (/run/log/journal/59eb6637b11f4d21afe4a34c2301b87c) is 6.0M, max 48.4M, 42.3M free. May 17 00:16:18.872762 systemd-modules-load[194]: Inserted module 'overlay' May 17 00:16:18.898177 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 17 00:16:18.900243 systemd-modules-load[194]: Inserted module 'br_netfilter' May 17 00:16:18.913536 kernel: Bridge firewalling registered May 17 00:16:18.915440 systemd[1]: Started systemd-journald.service - Journal Service. May 17 00:16:18.915857 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 17 00:16:18.918197 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 17 00:16:18.932329 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 17 00:16:18.935352 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 17 00:16:18.937999 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 17 00:16:18.943158 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 17 00:16:18.953353 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 17 00:16:18.954462 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 17 00:16:18.957184 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 17 00:16:18.961478 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 17 00:16:18.969290 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 17 00:16:18.972006 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 17 00:16:18.979090 dracut-cmdline[228]: dracut-dracut-053 May 17 00:16:18.982832 dracut-cmdline[228]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=6b60288baeea1613a76a6f06a8f0e8edc178eae4857ce00eac42d48e92ed015e May 17 00:16:19.006191 systemd-resolved[232]: Positive Trust Anchors: May 17 00:16:19.006209 systemd-resolved[232]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 17 00:16:19.006241 systemd-resolved[232]: 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 May 17 00:16:19.009088 systemd-resolved[232]: Defaulting to hostname 'linux'. May 17 00:16:19.010187 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 17 00:16:19.015119 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 17 00:16:19.084187 kernel: SCSI subsystem initialized May 17 00:16:19.093173 kernel: Loading iSCSI transport class v2.0-870. May 17 00:16:19.103173 kernel: iscsi: registered transport (tcp) May 17 00:16:19.124251 kernel: iscsi: registered transport (qla4xxx) May 17 00:16:19.124271 kernel: QLogic iSCSI HBA Driver May 17 00:16:19.175356 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 17 00:16:19.185380 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 17 00:16:19.209402 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 17 00:16:19.209429 kernel: device-mapper: uevent: version 1.0.3 May 17 00:16:19.210465 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 17 00:16:19.252185 kernel: raid6: avx2x4 gen() 30609 MB/s May 17 00:16:19.269168 kernel: raid6: avx2x2 gen() 31091 MB/s May 17 00:16:19.286254 kernel: raid6: avx2x1 gen() 26090 MB/s May 17 00:16:19.286271 kernel: raid6: using algorithm avx2x2 gen() 31091 MB/s May 17 00:16:19.304264 kernel: raid6: .... xor() 19957 MB/s, rmw enabled May 17 00:16:19.304281 kernel: raid6: using avx2x2 recovery algorithm May 17 00:16:19.325177 kernel: xor: automatically using best checksumming function avx May 17 00:16:19.480210 kernel: Btrfs loaded, zoned=no, fsverity=no May 17 00:16:19.494475 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 17 00:16:19.504331 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 17 00:16:19.516097 systemd-udevd[414]: Using default interface naming scheme 'v255'. May 17 00:16:19.520654 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 17 00:16:19.528319 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 17 00:16:19.541734 dracut-pre-trigger[419]: rd.md=0: removing MD RAID activation May 17 00:16:19.575793 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 17 00:16:19.587280 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 17 00:16:19.650563 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 17 00:16:19.662341 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 17 00:16:19.672217 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 17 00:16:19.674742 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 17 00:16:19.676003 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 17 00:16:19.677350 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 17 00:16:19.687380 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 17 00:16:19.696336 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues May 17 00:16:19.698397 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 17 00:16:19.698547 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 17 00:16:19.703213 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 17 00:16:19.703279 kernel: cryptd: max_cpu_qlen set to 1000 May 17 00:16:19.714341 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 17 00:16:19.715465 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 17 00:16:19.720425 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 17 00:16:19.720850 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 17 00:16:19.721804 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 17 00:16:19.725666 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 17 00:16:19.732726 kernel: BTRFS: device fsid 7f88d479-6686-439c-8052-b96f0a9d77bc devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (472) May 17 00:16:19.734176 kernel: libata version 3.00 loaded. May 17 00:16:19.742240 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (479) May 17 00:16:19.742267 kernel: ahci 0000:00:1f.2: version 3.0 May 17 00:16:19.742444 kernel: AVX2 version of gcm_enc/dec engaged. May 17 00:16:19.742460 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 May 17 00:16:19.742470 kernel: AES CTR mode by8 optimization enabled May 17 00:16:19.743417 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 17 00:16:19.750335 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode May 17 00:16:19.751237 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only May 17 00:16:19.751390 kernel: scsi host0: ahci May 17 00:16:19.751552 kernel: scsi host1: ahci May 17 00:16:19.753371 kernel: scsi host2: ahci May 17 00:16:19.753714 kernel: scsi host3: ahci May 17 00:16:19.755211 kernel: scsi host4: ahci May 17 00:16:19.757176 kernel: scsi host5: ahci May 17 00:16:19.757371 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 May 17 00:16:19.757383 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 May 17 00:16:19.758869 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 May 17 00:16:19.758901 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 May 17 00:16:19.758912 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 May 17 00:16:19.758844 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 17 00:16:19.794449 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 May 17 00:16:19.794197 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 17 00:16:19.807607 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 17 00:16:19.816038 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 17 00:16:19.816479 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 17 00:16:19.821219 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 17 00:16:19.833315 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 17 00:16:19.835746 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 17 00:16:19.843185 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 17 00:16:19.848181 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 17 00:16:19.861132 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 17 00:16:20.072922 kernel: ata6: SATA link down (SStatus 0 SControl 300) May 17 00:16:20.072998 kernel: ata4: SATA link down (SStatus 0 SControl 300) May 17 00:16:20.073017 kernel: ata1: SATA link down (SStatus 0 SControl 300) May 17 00:16:20.073027 kernel: ata5: SATA link down (SStatus 0 SControl 300) May 17 00:16:20.074179 kernel: ata2: SATA link down (SStatus 0 SControl 300) May 17 00:16:20.075178 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 17 00:16:20.076335 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 May 17 00:16:20.076346 kernel: ata3.00: applying bridge limits May 17 00:16:20.077183 kernel: ata3.00: configured for UDMA/100 May 17 00:16:20.077241 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 May 17 00:16:20.121750 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray May 17 00:16:20.121964 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 17 00:16:20.135174 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 May 17 00:16:20.852930 disk-uuid[559]: The operation has completed successfully. May 17 00:16:20.854380 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 17 00:16:20.881577 systemd[1]: disk-uuid.service: Deactivated successfully. May 17 00:16:20.881714 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 17 00:16:20.901265 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 17 00:16:20.904644 sh[594]: Success May 17 00:16:20.916183 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" May 17 00:16:20.947586 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 17 00:16:20.967511 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 17 00:16:20.971057 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 17 00:16:20.982533 kernel: BTRFS info (device dm-0): first mount of filesystem 7f88d479-6686-439c-8052-b96f0a9d77bc May 17 00:16:20.982578 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 17 00:16:20.982589 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 17 00:16:20.983578 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 17 00:16:20.984320 kernel: BTRFS info (device dm-0): using free space tree May 17 00:16:20.989351 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 17 00:16:20.990468 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 17 00:16:21.001320 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 17 00:16:21.002308 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 17 00:16:21.011184 kernel: BTRFS info (device vda6): first mount of filesystem a013fe34-315a-4c90-9ca1-aace1df6c4ac May 17 00:16:21.011212 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 17 00:16:21.011223 kernel: BTRFS info (device vda6): using free space tree May 17 00:16:21.014175 kernel: BTRFS info (device vda6): auto enabling async discard May 17 00:16:21.022846 systemd[1]: mnt-oem.mount: Deactivated successfully. May 17 00:16:21.024611 kernel: BTRFS info (device vda6): last unmount of filesystem a013fe34-315a-4c90-9ca1-aace1df6c4ac May 17 00:16:21.032476 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 17 00:16:21.041336 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 17 00:16:21.090595 ignition[680]: Ignition 2.19.0 May 17 00:16:21.090608 ignition[680]: Stage: fetch-offline May 17 00:16:21.090642 ignition[680]: no configs at "/usr/lib/ignition/base.d" May 17 00:16:21.090652 ignition[680]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 17 00:16:21.090739 ignition[680]: parsed url from cmdline: "" May 17 00:16:21.090743 ignition[680]: no config URL provided May 17 00:16:21.090749 ignition[680]: reading system config file "/usr/lib/ignition/user.ign" May 17 00:16:21.090757 ignition[680]: no config at "/usr/lib/ignition/user.ign" May 17 00:16:21.090783 ignition[680]: op(1): [started] loading QEMU firmware config module May 17 00:16:21.090789 ignition[680]: op(1): executing: "modprobe" "qemu_fw_cfg" May 17 00:16:21.099535 ignition[680]: op(1): [finished] loading QEMU firmware config module May 17 00:16:21.100811 ignition[680]: parsing config with SHA512: f9916dccaca5c9fb08ac8d3eff7354a2025e4459fa25b438bee8caac6d55d8d86f4e165eebb0a62ee01603b6b3be813d84ffc918409dd922d8598c5823784122 May 17 00:16:21.106305 unknown[680]: fetched base config from "system" May 17 00:16:21.106325 unknown[680]: fetched user config from "qemu" May 17 00:16:21.106541 ignition[680]: fetch-offline: fetch-offline passed May 17 00:16:21.107404 ignition[680]: Ignition finished successfully May 17 00:16:21.111786 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 17 00:16:21.134260 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 17 00:16:21.143416 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 17 00:16:21.167515 systemd-networkd[787]: lo: Link UP May 17 00:16:21.167527 systemd-networkd[787]: lo: Gained carrier May 17 00:16:21.170659 systemd-networkd[787]: Enumeration completed May 17 00:16:21.171074 systemd-networkd[787]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 17 00:16:21.171078 systemd-networkd[787]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 17 00:16:21.171729 systemd[1]: Started systemd-networkd.service - Network Configuration. May 17 00:16:21.171860 systemd-networkd[787]: eth0: Link UP May 17 00:16:21.171864 systemd-networkd[787]: eth0: Gained carrier May 17 00:16:21.171870 systemd-networkd[787]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 17 00:16:21.178730 systemd[1]: Reached target network.target - Network. May 17 00:16:21.182503 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 17 00:16:21.195183 systemd-networkd[787]: eth0: DHCPv4 address 10.0.0.72/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 17 00:16:21.198370 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 17 00:16:21.211178 ignition[789]: Ignition 2.19.0 May 17 00:16:21.211188 ignition[789]: Stage: kargs May 17 00:16:21.211361 ignition[789]: no configs at "/usr/lib/ignition/base.d" May 17 00:16:21.211374 ignition[789]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 17 00:16:21.212000 ignition[789]: kargs: kargs passed May 17 00:16:21.212043 ignition[789]: Ignition finished successfully May 17 00:16:21.218902 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 17 00:16:21.227311 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 17 00:16:21.240321 ignition[799]: Ignition 2.19.0 May 17 00:16:21.240332 ignition[799]: Stage: disks May 17 00:16:21.240489 ignition[799]: no configs at "/usr/lib/ignition/base.d" May 17 00:16:21.240500 ignition[799]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 17 00:16:21.241046 ignition[799]: disks: disks passed May 17 00:16:21.241082 ignition[799]: Ignition finished successfully May 17 00:16:21.247052 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 17 00:16:21.249230 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 17 00:16:21.249687 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 17 00:16:21.250037 systemd[1]: Reached target local-fs.target - Local File Systems. May 17 00:16:21.250546 systemd[1]: Reached target sysinit.target - System Initialization. May 17 00:16:21.250873 systemd[1]: Reached target basic.target - Basic System. May 17 00:16:21.268306 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 17 00:16:21.282670 systemd-fsck[809]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 17 00:16:21.289102 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 17 00:16:21.300246 systemd[1]: Mounting sysroot.mount - /sysroot... May 17 00:16:21.385179 kernel: EXT4-fs (vda9): mounted filesystem 278698a4-82b6-49b4-b6df-f7999ed4e35e r/w with ordered data mode. Quota mode: none. May 17 00:16:21.386030 systemd[1]: Mounted sysroot.mount - /sysroot. May 17 00:16:21.387107 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 17 00:16:21.394241 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 17 00:16:21.395327 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 17 00:16:21.397109 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 17 00:16:21.397144 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 17 00:16:21.408327 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (817) May 17 00:16:21.408352 kernel: BTRFS info (device vda6): first mount of filesystem a013fe34-315a-4c90-9ca1-aace1df6c4ac May 17 00:16:21.408363 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 17 00:16:21.408374 kernel: BTRFS info (device vda6): using free space tree May 17 00:16:21.397178 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 17 00:16:21.403132 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 17 00:16:21.412534 kernel: BTRFS info (device vda6): auto enabling async discard May 17 00:16:21.409093 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 17 00:16:21.413306 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 17 00:16:21.443001 initrd-setup-root[841]: cut: /sysroot/etc/passwd: No such file or directory May 17 00:16:21.446959 initrd-setup-root[848]: cut: /sysroot/etc/group: No such file or directory May 17 00:16:21.450769 initrd-setup-root[855]: cut: /sysroot/etc/shadow: No such file or directory May 17 00:16:21.454507 initrd-setup-root[862]: cut: /sysroot/etc/gshadow: No such file or directory May 17 00:16:21.538001 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 17 00:16:21.551244 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 17 00:16:21.554415 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 17 00:16:21.559180 kernel: BTRFS info (device vda6): last unmount of filesystem a013fe34-315a-4c90-9ca1-aace1df6c4ac May 17 00:16:21.578913 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 17 00:16:21.580953 ignition[929]: INFO : Ignition 2.19.0 May 17 00:16:21.580953 ignition[929]: INFO : Stage: mount May 17 00:16:21.580953 ignition[929]: INFO : no configs at "/usr/lib/ignition/base.d" May 17 00:16:21.580953 ignition[929]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 17 00:16:21.580953 ignition[929]: INFO : mount: mount passed May 17 00:16:21.580953 ignition[929]: INFO : Ignition finished successfully May 17 00:16:21.582641 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 17 00:16:21.595244 systemd[1]: Starting ignition-files.service - Ignition (files)... May 17 00:16:21.981809 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 17 00:16:21.995393 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 17 00:16:22.002167 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (944) May 17 00:16:22.002193 kernel: BTRFS info (device vda6): first mount of filesystem a013fe34-315a-4c90-9ca1-aace1df6c4ac May 17 00:16:22.003442 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 17 00:16:22.003455 kernel: BTRFS info (device vda6): using free space tree May 17 00:16:22.006164 kernel: BTRFS info (device vda6): auto enabling async discard May 17 00:16:22.007877 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 17 00:16:22.027389 ignition[961]: INFO : Ignition 2.19.0 May 17 00:16:22.027389 ignition[961]: INFO : Stage: files May 17 00:16:22.029006 ignition[961]: INFO : no configs at "/usr/lib/ignition/base.d" May 17 00:16:22.029006 ignition[961]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 17 00:16:22.031548 ignition[961]: DEBUG : files: compiled without relabeling support, skipping May 17 00:16:22.032914 ignition[961]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 17 00:16:22.032914 ignition[961]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 17 00:16:22.037023 ignition[961]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 17 00:16:22.038736 ignition[961]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 17 00:16:22.040445 unknown[961]: wrote ssh authorized keys file for user: core May 17 00:16:22.041597 ignition[961]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 17 00:16:22.043889 ignition[961]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 17 00:16:22.045810 ignition[961]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 17 00:16:22.047554 ignition[961]: INFO : files: op(4): [started] processing unit "etcd-member.service" May 17 00:16:22.048998 ignition[961]: 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" May 17 00:16:22.051581 ignition[961]: 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" May 17 00:16:22.051581 ignition[961]: INFO : files: op(4): [finished] processing unit "etcd-member.service" May 17 00:16:22.051581 ignition[961]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" May 17 00:16:22.056294 ignition[961]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 17 00:16:22.058236 ignition[961]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 17 00:16:22.058236 ignition[961]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" May 17 00:16:22.061371 ignition[961]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" May 17 00:16:22.084262 ignition[961]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" May 17 00:16:22.090102 ignition[961]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 17 00:16:22.091620 ignition[961]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" May 17 00:16:22.091620 ignition[961]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" May 17 00:16:22.091620 ignition[961]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" May 17 00:16:22.091620 ignition[961]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 17 00:16:22.091620 ignition[961]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 17 00:16:22.091620 ignition[961]: INFO : files: files passed May 17 00:16:22.091620 ignition[961]: INFO : Ignition finished successfully May 17 00:16:22.098578 systemd[1]: Finished ignition-files.service - Ignition (files). May 17 00:16:22.106405 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 17 00:16:22.109283 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 17 00:16:22.111941 systemd[1]: ignition-quench.service: Deactivated successfully. May 17 00:16:22.112981 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 17 00:16:22.118949 initrd-setup-root-after-ignition[989]: grep: /sysroot/oem/oem-release: No such file or directory May 17 00:16:22.122803 initrd-setup-root-after-ignition[991]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 17 00:16:22.124720 initrd-setup-root-after-ignition[991]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 17 00:16:22.126584 initrd-setup-root-after-ignition[995]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 17 00:16:22.129990 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 17 00:16:22.132688 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 17 00:16:22.143295 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 17 00:16:22.169107 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 17 00:16:22.169252 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 17 00:16:22.169862 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 17 00:16:22.172912 systemd[1]: Reached target initrd.target - Initrd Default Target. May 17 00:16:22.173458 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 17 00:16:22.174205 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 17 00:16:22.192793 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 17 00:16:22.200416 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 17 00:16:22.209398 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 17 00:16:22.211720 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 17 00:16:22.214035 systemd[1]: Stopped target timers.target - Timer Units. May 17 00:16:22.215827 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 17 00:16:22.216832 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 17 00:16:22.219358 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 17 00:16:22.221368 systemd[1]: Stopped target basic.target - Basic System. May 17 00:16:22.223145 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 17 00:16:22.225310 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 17 00:16:22.227624 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 17 00:16:22.229810 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 17 00:16:22.231843 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 17 00:16:22.234277 systemd[1]: Stopped target sysinit.target - System Initialization. May 17 00:16:22.236311 systemd[1]: Stopped target local-fs.target - Local File Systems. May 17 00:16:22.238288 systemd[1]: Stopped target swap.target - Swaps. May 17 00:16:22.239870 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 17 00:16:22.240873 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 17 00:16:22.243104 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 17 00:16:22.245240 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 17 00:16:22.247536 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 17 00:16:22.248548 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 17 00:16:22.251097 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 17 00:16:22.252087 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 17 00:16:22.254292 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 17 00:16:22.255374 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 17 00:16:22.257679 systemd[1]: Stopped target paths.target - Path Units. May 17 00:16:22.259392 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 17 00:16:22.264213 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 17 00:16:22.266874 systemd[1]: Stopped target slices.target - Slice Units. May 17 00:16:22.268650 systemd[1]: Stopped target sockets.target - Socket Units. May 17 00:16:22.270480 systemd[1]: iscsid.socket: Deactivated successfully. May 17 00:16:22.271341 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 17 00:16:22.273249 systemd[1]: iscsiuio.socket: Deactivated successfully. May 17 00:16:22.274118 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 17 00:16:22.276140 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 17 00:16:22.277304 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 17 00:16:22.279773 systemd[1]: ignition-files.service: Deactivated successfully. May 17 00:16:22.280737 systemd[1]: Stopped ignition-files.service - Ignition (files). May 17 00:16:22.293294 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 17 00:16:22.295118 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 17 00:16:22.296126 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 17 00:16:22.299196 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 17 00:16:22.300937 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 17 00:16:22.302084 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 17 00:16:22.304480 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 17 00:16:22.305535 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 17 00:16:22.307859 ignition[1015]: INFO : Ignition 2.19.0 May 17 00:16:22.307859 ignition[1015]: INFO : Stage: umount May 17 00:16:22.307859 ignition[1015]: INFO : no configs at "/usr/lib/ignition/base.d" May 17 00:16:22.307859 ignition[1015]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 17 00:16:22.307859 ignition[1015]: INFO : umount: umount passed May 17 00:16:22.307859 ignition[1015]: INFO : Ignition finished successfully May 17 00:16:22.310459 systemd[1]: ignition-mount.service: Deactivated successfully. May 17 00:16:22.310566 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 17 00:16:22.314135 systemd[1]: Stopped target network.target - Network. May 17 00:16:22.315141 systemd[1]: ignition-disks.service: Deactivated successfully. May 17 00:16:22.315209 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 17 00:16:22.317112 systemd[1]: ignition-kargs.service: Deactivated successfully. May 17 00:16:22.317171 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 17 00:16:22.319127 systemd[1]: ignition-setup.service: Deactivated successfully. May 17 00:16:22.319184 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 17 00:16:22.320923 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 17 00:16:22.320978 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 17 00:16:22.323002 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 17 00:16:22.324871 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 17 00:16:22.326204 systemd-networkd[787]: eth0: DHCPv6 lease lost May 17 00:16:22.326966 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 17 00:16:22.327067 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 17 00:16:22.329016 systemd[1]: systemd-networkd.service: Deactivated successfully. May 17 00:16:22.329122 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 17 00:16:22.331878 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 17 00:16:22.332350 systemd[1]: systemd-resolved.service: Deactivated successfully. May 17 00:16:22.332465 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 17 00:16:22.336283 systemd[1]: sysroot-boot.service: Deactivated successfully. May 17 00:16:22.336456 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 17 00:16:22.340819 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 17 00:16:22.340884 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 17 00:16:22.342518 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 17 00:16:22.342584 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 17 00:16:22.356277 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 17 00:16:22.357195 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 17 00:16:22.357252 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 17 00:16:22.359315 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 17 00:16:22.359361 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 17 00:16:22.361434 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 17 00:16:22.361479 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 17 00:16:22.363732 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 17 00:16:22.363777 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 17 00:16:22.366132 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 17 00:16:22.375944 systemd[1]: network-cleanup.service: Deactivated successfully. May 17 00:16:22.376108 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 17 00:16:22.394191 systemd[1]: systemd-udevd.service: Deactivated successfully. May 17 00:16:22.394417 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 17 00:16:22.395272 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 17 00:16:22.395329 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 17 00:16:22.398021 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 17 00:16:22.398062 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 17 00:16:22.398483 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 17 00:16:22.398537 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 17 00:16:22.399198 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 17 00:16:22.399247 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 17 00:16:22.400013 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 17 00:16:22.400062 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 17 00:16:22.415427 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 17 00:16:22.416543 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 17 00:16:22.416619 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 17 00:16:22.418864 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 17 00:16:22.418924 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 17 00:16:22.422965 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 17 00:16:22.423082 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 17 00:16:22.424538 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 17 00:16:22.435279 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 17 00:16:22.442847 systemd[1]: Switching root. May 17 00:16:22.473498 systemd-journald[191]: Journal stopped May 17 00:16:23.303352 systemd-journald[191]: Received SIGTERM from PID 1 (systemd). May 17 00:16:23.303440 kernel: SELinux: policy capability network_peer_controls=1 May 17 00:16:23.303459 kernel: SELinux: policy capability open_perms=1 May 17 00:16:23.303475 kernel: SELinux: policy capability extended_socket_class=1 May 17 00:16:23.303489 kernel: SELinux: policy capability always_check_network=0 May 17 00:16:23.303504 kernel: SELinux: policy capability cgroup_seclabel=1 May 17 00:16:23.303519 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 17 00:16:23.303534 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 17 00:16:23.303549 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 17 00:16:23.303564 kernel: audit: type=1403 audit(1747440982.595:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 17 00:16:23.303589 systemd[1]: Successfully loaded SELinux policy in 41.542ms. May 17 00:16:23.303614 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 12.442ms. May 17 00:16:23.303632 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 17 00:16:23.303648 systemd[1]: Detected virtualization kvm. May 17 00:16:23.303664 systemd[1]: Detected architecture x86-64. May 17 00:16:23.303680 systemd[1]: Detected first boot. May 17 00:16:23.303696 systemd[1]: Initializing machine ID from VM UUID. May 17 00:16:23.303711 zram_generator::config[1060]: No configuration found. May 17 00:16:23.303738 systemd[1]: Populated /etc with preset unit settings. May 17 00:16:23.303754 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 17 00:16:23.303773 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 17 00:16:23.303796 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 17 00:16:23.303814 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 17 00:16:23.303830 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 17 00:16:23.303846 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 17 00:16:23.303862 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 17 00:16:23.303879 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 17 00:16:23.303900 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 17 00:16:23.303916 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 17 00:16:23.303942 systemd[1]: Created slice user.slice - User and Session Slice. May 17 00:16:23.303959 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 17 00:16:23.303976 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 17 00:16:23.303996 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 17 00:16:23.304013 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 17 00:16:23.304029 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 17 00:16:23.304045 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 17 00:16:23.304061 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 17 00:16:23.304077 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 17 00:16:23.304094 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 17 00:16:23.304109 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 17 00:16:23.304126 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 17 00:16:23.304164 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 17 00:16:23.304185 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 17 00:16:23.304202 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 17 00:16:23.304217 systemd[1]: Reached target slices.target - Slice Units. May 17 00:16:23.304233 systemd[1]: Reached target swap.target - Swaps. May 17 00:16:23.304249 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 17 00:16:23.304264 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 17 00:16:23.304280 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 17 00:16:23.304300 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 17 00:16:23.304316 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 17 00:16:23.304332 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 17 00:16:23.304348 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 17 00:16:23.304363 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 17 00:16:23.304379 systemd[1]: Mounting media.mount - External Media Directory... May 17 00:16:23.304395 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:23.304411 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 17 00:16:23.304426 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 17 00:16:23.304445 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 17 00:16:23.304462 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). May 17 00:16:23.304478 systemd[1]: Reached target machines.target - Containers. May 17 00:16:23.304494 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 17 00:16:23.304511 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 17 00:16:23.304528 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 17 00:16:23.304543 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 17 00:16:23.304559 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 17 00:16:23.304578 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 17 00:16:23.304594 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 17 00:16:23.304610 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 17 00:16:23.304625 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 17 00:16:23.304642 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 17 00:16:23.304658 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 17 00:16:23.304674 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 17 00:16:23.304695 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 17 00:16:23.304712 systemd[1]: Stopped systemd-fsck-usr.service. May 17 00:16:23.304731 systemd[1]: Starting systemd-journald.service - Journal Service... May 17 00:16:23.304748 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 17 00:16:23.304764 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 17 00:16:23.304779 kernel: loop: module loaded May 17 00:16:23.304795 kernel: fuse: init (API version 7.39) May 17 00:16:23.304810 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 17 00:16:23.304851 systemd-journald[1123]: Collecting audit messages is disabled. May 17 00:16:23.304891 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 17 00:16:23.304911 systemd-journald[1123]: Journal started May 17 00:16:23.304950 systemd-journald[1123]: Runtime Journal (/run/log/journal/59eb6637b11f4d21afe4a34c2301b87c) is 6.0M, max 48.4M, 42.3M free. May 17 00:16:23.087964 systemd[1]: Queued start job for default target multi-user.target. May 17 00:16:23.109448 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 17 00:16:23.109884 systemd[1]: systemd-journald.service: Deactivated successfully. May 17 00:16:23.306199 systemd[1]: verity-setup.service: Deactivated successfully. May 17 00:16:23.306226 systemd[1]: Stopped verity-setup.service. May 17 00:16:23.310257 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:23.314254 systemd[1]: Started systemd-journald.service - Journal Service. May 17 00:16:23.315502 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 17 00:16:23.316879 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 17 00:16:23.318855 systemd[1]: Mounted media.mount - External Media Directory. May 17 00:16:23.320272 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 17 00:16:23.322388 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 17 00:16:23.323861 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 17 00:16:23.325379 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 17 00:16:23.327277 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 17 00:16:23.327515 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 17 00:16:23.329343 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 17 00:16:23.329551 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 17 00:16:23.331340 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 17 00:16:23.331558 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 17 00:16:23.333444 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 17 00:16:23.333675 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 17 00:16:23.335624 systemd[1]: modprobe@loop.service: Deactivated successfully. May 17 00:16:23.335838 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 17 00:16:23.337175 kernel: ACPI: bus type drm_connector registered May 17 00:16:23.338386 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 17 00:16:23.340300 systemd[1]: modprobe@drm.service: Deactivated successfully. May 17 00:16:23.340532 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 17 00:16:23.343129 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 17 00:16:23.345290 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 17 00:16:23.358005 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 17 00:16:23.364320 systemd[1]: Reached target network-pre.target - Preparation for Network. May 17 00:16:23.380556 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 17 00:16:23.383256 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 17 00:16:23.384509 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 17 00:16:23.384543 systemd[1]: Reached target local-fs.target - Local File Systems. May 17 00:16:23.386571 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). May 17 00:16:23.388914 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 17 00:16:23.394271 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 17 00:16:23.395627 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 17 00:16:23.398772 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 17 00:16:23.403846 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 17 00:16:23.405389 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 17 00:16:23.407113 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 17 00:16:23.411255 systemd-journald[1123]: Time spent on flushing to /var/log/journal/59eb6637b11f4d21afe4a34c2301b87c is 13.201ms for 918 entries. May 17 00:16:23.411255 systemd-journald[1123]: System Journal (/var/log/journal/59eb6637b11f4d21afe4a34c2301b87c) is 8.0M, max 195.6M, 187.6M free. May 17 00:16:23.432582 systemd-journald[1123]: Received client request to flush runtime journal. May 17 00:16:23.412366 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 17 00:16:23.414371 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 17 00:16:23.418346 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 17 00:16:23.423443 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 17 00:16:23.427043 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 17 00:16:23.429439 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 17 00:16:23.435394 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 17 00:16:23.437429 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 17 00:16:23.439700 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 17 00:16:23.443133 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 17 00:16:23.446774 kernel: loop0: detected capacity change from 0 to 140768 May 17 00:16:23.449481 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 17 00:16:23.460461 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... May 17 00:16:23.463711 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 17 00:16:23.471666 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 17 00:16:23.476182 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 17 00:16:23.486643 udevadm[1188]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 17 00:16:23.500672 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 17 00:16:23.501578 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 17 00:16:23.503318 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. May 17 00:16:23.515472 kernel: loop1: detected capacity change from 0 to 142488 May 17 00:16:23.515001 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 17 00:16:23.537319 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. May 17 00:16:23.537346 systemd-tmpfiles[1193]: ACLs are not supported, ignoring. May 17 00:16:23.545300 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 17 00:16:23.555175 kernel: loop2: detected capacity change from 0 to 140768 May 17 00:16:23.569191 kernel: loop3: detected capacity change from 0 to 142488 May 17 00:16:23.578219 (sd-merge)[1198]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 17 00:16:23.578839 (sd-merge)[1198]: Merged extensions into '/usr'. May 17 00:16:23.582461 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 17 00:16:23.591592 systemd[1]: Starting ensure-sysext.service... May 17 00:16:23.599458 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 17 00:16:23.603879 systemd[1]: Reloading requested from client PID 1200 ('systemctl') (unit ensure-sysext.service)... May 17 00:16:23.603897 systemd[1]: Reloading... May 17 00:16:23.626823 systemd-tmpfiles[1201]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 17 00:16:23.627324 systemd-tmpfiles[1201]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 17 00:16:23.628561 systemd-tmpfiles[1201]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 17 00:16:23.628962 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. May 17 00:16:23.629058 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. May 17 00:16:23.636313 systemd-tmpfiles[1201]: Detected autofs mount point /boot during canonicalization of boot. May 17 00:16:23.636332 systemd-tmpfiles[1201]: Skipping /boot May 17 00:16:23.651937 systemd-tmpfiles[1201]: Detected autofs mount point /boot during canonicalization of boot. May 17 00:16:23.651960 systemd-tmpfiles[1201]: Skipping /boot May 17 00:16:23.672178 zram_generator::config[1228]: No configuration found. May 17 00:16:23.798693 ldconfig[1169]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 17 00:16:23.802207 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 17 00:16:23.849000 systemd[1]: Reloading finished in 244 ms. May 17 00:16:23.884929 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 17 00:16:23.898545 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 17 00:16:23.906972 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... May 17 00:16:23.909508 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 17 00:16:23.915269 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 17 00:16:23.920200 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 17 00:16:23.923173 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 17 00:16:23.926612 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:23.927039 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 17 00:16:23.930476 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 17 00:16:23.934369 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 17 00:16:23.938195 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 17 00:16:23.939504 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 17 00:16:23.941741 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 17 00:16:23.942811 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:23.944374 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 17 00:16:23.944563 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 17 00:16:23.946332 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 17 00:16:23.946509 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 17 00:16:23.948374 systemd[1]: modprobe@loop.service: Deactivated successfully. May 17 00:16:23.948572 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 17 00:16:23.956339 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:23.956769 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 17 00:16:23.958372 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 17 00:16:23.962480 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 17 00:16:23.969423 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 17 00:16:23.971621 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 17 00:16:23.971737 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:23.972700 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 17 00:16:23.974997 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 17 00:16:23.977189 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 17 00:16:23.978907 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 17 00:16:23.979523 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 17 00:16:23.982724 systemd[1]: modprobe@loop.service: Deactivated successfully. May 17 00:16:23.982895 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 17 00:16:23.985360 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 17 00:16:23.993999 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:23.994816 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 17 00:16:24.006523 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 17 00:16:24.009750 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 17 00:16:24.012551 augenrules[1306]: No rules May 17 00:16:24.012941 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 17 00:16:24.018394 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 17 00:16:24.019964 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 17 00:16:24.020105 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 17 00:16:24.021078 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 17 00:16:24.024427 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 17 00:16:24.026235 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. May 17 00:16:24.028083 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 17 00:16:24.028378 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 17 00:16:24.030012 systemd[1]: modprobe@drm.service: Deactivated successfully. May 17 00:16:24.030211 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 17 00:16:24.031852 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 17 00:16:24.033575 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 17 00:16:24.033750 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 17 00:16:24.035440 systemd[1]: modprobe@loop.service: Deactivated successfully. May 17 00:16:24.035611 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 17 00:16:24.043010 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 17 00:16:24.043246 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 17 00:16:24.049305 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 17 00:16:24.051733 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 17 00:16:24.052869 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). May 17 00:16:24.053368 systemd[1]: Finished ensure-sysext.service. May 17 00:16:24.058309 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 17 00:16:24.072469 systemd-resolved[1271]: Positive Trust Anchors: May 17 00:16:24.072486 systemd-resolved[1271]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 17 00:16:24.072517 systemd-resolved[1271]: 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 May 17 00:16:24.076312 systemd-resolved[1271]: Defaulting to hostname 'linux'. May 17 00:16:24.084618 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 17 00:16:24.086051 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 17 00:16:24.087117 systemd-udevd[1323]: Using default interface naming scheme 'v255'. May 17 00:16:24.087800 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 17 00:16:24.104233 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 17 00:16:24.115404 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 17 00:16:24.150203 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 17 00:16:24.155191 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 17 00:16:24.156747 systemd[1]: Reached target time-set.target - System Time Set. May 17 00:16:24.176766 systemd-networkd[1333]: lo: Link UP May 17 00:16:24.176781 systemd-networkd[1333]: lo: Gained carrier May 17 00:16:24.178492 systemd-networkd[1333]: Enumeration completed May 17 00:16:24.178576 systemd[1]: Started systemd-networkd.service - Network Configuration. May 17 00:16:24.180630 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 17 00:16:24.180638 systemd-networkd[1333]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 17 00:16:24.182193 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1340) May 17 00:16:24.183431 systemd-networkd[1333]: eth0: Link UP May 17 00:16:24.183443 systemd-networkd[1333]: eth0: Gained carrier May 17 00:16:24.183455 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 17 00:16:24.183975 systemd[1]: Reached target network.target - Network. May 17 00:16:24.191430 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 17 00:16:24.194215 systemd-networkd[1333]: eth0: DHCPv4 address 10.0.0.72/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 17 00:16:24.195036 systemd-timesyncd[1326]: Network configuration changed, trying to establish connection. May 17 00:16:24.934105 systemd-timesyncd[1326]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 17 00:16:24.934165 systemd-timesyncd[1326]: Initial clock synchronization to Sat 2025-05-17 00:16:24.933921 UTC. May 17 00:16:24.934846 systemd-resolved[1271]: Clock change detected. Flushing caches. May 17 00:16:24.941078 systemd-networkd[1333]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 17 00:16:24.955561 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 17 00:16:24.963387 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 May 17 00:16:24.968543 kernel: ACPI: button: Power Button [PWRF] May 17 00:16:24.971382 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 17 00:16:24.971681 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) May 17 00:16:24.971914 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD May 17 00:16:24.970532 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 17 00:16:24.979676 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 May 17 00:16:25.005664 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 17 00:16:25.007470 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 17 00:16:25.025379 kernel: mousedev: PS/2 mouse device common for all mice May 17 00:16:25.102843 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 17 00:16:25.103378 kernel: kvm_amd: TSC scaling supported May 17 00:16:25.103402 kernel: kvm_amd: Nested Virtualization enabled May 17 00:16:25.103415 kernel: kvm_amd: Nested Paging enabled May 17 00:16:25.103426 kernel: kvm_amd: LBR virtualization supported May 17 00:16:25.103451 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported May 17 00:16:25.103469 kernel: kvm_amd: Virtual GIF supported May 17 00:16:25.126384 kernel: EDAC MC: Ver: 3.0.0 May 17 00:16:25.155755 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 17 00:16:25.167511 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 17 00:16:25.192225 lvm[1373]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 17 00:16:25.221950 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 17 00:16:25.223561 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 17 00:16:25.224694 systemd[1]: Reached target sysinit.target - System Initialization. May 17 00:16:25.225883 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 17 00:16:25.227161 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 17 00:16:25.228647 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 17 00:16:25.229811 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 17 00:16:25.231073 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 17 00:16:25.232383 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 17 00:16:25.232413 systemd[1]: Reached target paths.target - Path Units. May 17 00:16:25.233336 systemd[1]: Reached target timers.target - Timer Units. May 17 00:16:25.235044 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 17 00:16:25.237777 systemd[1]: Starting docker.socket - Docker Socket for the API... May 17 00:16:25.250997 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 17 00:16:25.253387 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 17 00:16:25.254944 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 17 00:16:25.256128 systemd[1]: Reached target sockets.target - Socket Units. May 17 00:16:25.257104 systemd[1]: Reached target basic.target - Basic System. May 17 00:16:25.258103 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 17 00:16:25.258152 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 17 00:16:25.259125 systemd[1]: Starting containerd.service - containerd container runtime... May 17 00:16:25.261242 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 17 00:16:25.265451 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 17 00:16:25.268608 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 17 00:16:25.269996 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). May 17 00:16:25.272398 lvm[1377]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 17 00:16:25.273999 jq[1380]: false May 17 00:16:25.274517 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 17 00:16:25.278612 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 17 00:16:25.280975 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 17 00:16:25.286509 systemd[1]: Starting systemd-logind.service - User Login Management... May 17 00:16:25.288797 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 17 00:16:25.289194 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 17 00:16:25.289947 systemd[1]: Starting update-engine.service - Update Engine... May 17 00:16:25.294464 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 17 00:16:25.298817 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 17 00:16:25.299321 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 17 00:16:25.299874 systemd[1]: motdgen.service: Deactivated successfully. May 17 00:16:25.300191 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 17 00:16:25.302594 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 17 00:16:25.302849 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 17 00:16:25.306603 extend-filesystems[1381]: Found loop2 May 17 00:16:25.306603 extend-filesystems[1381]: Found loop3 May 17 00:16:25.306603 extend-filesystems[1381]: Found sr0 May 17 00:16:25.306603 extend-filesystems[1381]: Found vda May 17 00:16:25.306603 extend-filesystems[1381]: Found vda1 May 17 00:16:25.306603 extend-filesystems[1381]: Found vda2 May 17 00:16:25.306603 extend-filesystems[1381]: Found vda3 May 17 00:16:25.306603 extend-filesystems[1381]: Found usr May 17 00:16:25.306603 extend-filesystems[1381]: Found vda4 May 17 00:16:25.306603 extend-filesystems[1381]: Found vda6 May 17 00:16:25.306603 extend-filesystems[1381]: Found vda7 May 17 00:16:25.306603 extend-filesystems[1381]: Found vda9 May 17 00:16:25.306603 extend-filesystems[1381]: Checking size of /dev/vda9 May 17 00:16:25.305050 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 17 00:16:25.342310 update_engine[1391]: I20250517 00:16:25.314973 1391 main.cc:92] Flatcar Update Engine starting May 17 00:16:25.342310 update_engine[1391]: I20250517 00:16:25.316227 1391 update_check_scheduler.cc:74] Next update check in 4m5s May 17 00:16:25.309477 dbus-daemon[1379]: [system] SELinux support is enabled May 17 00:16:25.342778 extend-filesystems[1381]: Old size kept for /dev/vda9 May 17 00:16:25.347667 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1342) May 17 00:16:25.315236 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 17 00:16:25.320213 (ntainerd)[1400]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 17 00:16:25.348069 jq[1393]: true May 17 00:16:25.322798 systemd[1]: extend-filesystems.service: Deactivated successfully. May 17 00:16:25.323011 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 17 00:16:25.348391 jq[1403]: true May 17 00:16:25.340896 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). May 17 00:16:25.340920 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 17 00:16:25.343561 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). May 17 00:16:25.343575 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 17 00:16:25.376585 systemd[1]: Started update-engine.service - Update Engine. May 17 00:16:25.387523 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 17 00:16:25.395792 systemd-logind[1388]: Watching system buttons on /dev/input/event1 (Power Button) May 17 00:16:25.395819 systemd-logind[1388]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 17 00:16:25.396515 systemd-logind[1388]: New seat seat0. May 17 00:16:25.397576 systemd[1]: Started systemd-logind.service - User Login Management. May 17 00:16:25.445071 locksmithd[1415]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 17 00:16:25.446290 bash[1429]: Updated "/home/core/.ssh/authorized_keys" May 17 00:16:25.448349 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 17 00:16:25.450426 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 17 00:16:25.562213 containerd[1400]: time="2025-05-17T00:16:25.562075201Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 May 17 00:16:25.585155 sshd_keygen[1399]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 17 00:16:25.585679 containerd[1400]: time="2025-05-17T00:16:25.585605958Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 17 00:16:25.588314 containerd[1400]: time="2025-05-17T00:16:25.587856760Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.90-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 17 00:16:25.588314 containerd[1400]: time="2025-05-17T00:16:25.587894460Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 17 00:16:25.588314 containerd[1400]: time="2025-05-17T00:16:25.587911502Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 17 00:16:25.588314 containerd[1400]: time="2025-05-17T00:16:25.588105446Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 17 00:16:25.588314 containerd[1400]: time="2025-05-17T00:16:25.588131866Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 17 00:16:25.588314 containerd[1400]: time="2025-05-17T00:16:25.588196487Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 17 00:16:25.588314 containerd[1400]: time="2025-05-17T00:16:25.588209692Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 17 00:16:25.588576 containerd[1400]: time="2025-05-17T00:16:25.588426288Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 17 00:16:25.588576 containerd[1400]: time="2025-05-17T00:16:25.588444823Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 17 00:16:25.588576 containerd[1400]: time="2025-05-17T00:16:25.588458098Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 17 00:16:25.588576 containerd[1400]: time="2025-05-17T00:16:25.588470150Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 17 00:16:25.588576 containerd[1400]: time="2025-05-17T00:16:25.588560981Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 17 00:16:25.588855 containerd[1400]: time="2025-05-17T00:16:25.588842959Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 17 00:16:25.588995 containerd[1400]: time="2025-05-17T00:16:25.588962303Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 17 00:16:25.588995 containerd[1400]: time="2025-05-17T00:16:25.588985337Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 17 00:16:25.589120 containerd[1400]: time="2025-05-17T00:16:25.589084122Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 17 00:16:25.589202 containerd[1400]: time="2025-05-17T00:16:25.589174762Z" level=info msg="metadata content store policy set" policy=shared May 17 00:16:25.598962 containerd[1400]: time="2025-05-17T00:16:25.598921794Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 17 00:16:25.599019 containerd[1400]: time="2025-05-17T00:16:25.598972029Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 17 00:16:25.599019 containerd[1400]: time="2025-05-17T00:16:25.598987137Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 17 00:16:25.599019 containerd[1400]: time="2025-05-17T00:16:25.599002336Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 17 00:16:25.599083 containerd[1400]: time="2025-05-17T00:16:25.599021121Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 17 00:16:25.599199 containerd[1400]: time="2025-05-17T00:16:25.599172875Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 17 00:16:25.599535 containerd[1400]: time="2025-05-17T00:16:25.599488327Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 17 00:16:25.599705 containerd[1400]: time="2025-05-17T00:16:25.599676721Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 17 00:16:25.599728 containerd[1400]: time="2025-05-17T00:16:25.599703811Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 17 00:16:25.599747 containerd[1400]: time="2025-05-17T00:16:25.599722486Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 17 00:16:25.599747 containerd[1400]: time="2025-05-17T00:16:25.599741522Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599797 containerd[1400]: time="2025-05-17T00:16:25.599759035Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599797 containerd[1400]: time="2025-05-17T00:16:25.599780024Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599832 containerd[1400]: time="2025-05-17T00:16:25.599796285Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599832 containerd[1400]: time="2025-05-17T00:16:25.599821462Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599868 containerd[1400]: time="2025-05-17T00:16:25.599838123Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599868 containerd[1400]: time="2025-05-17T00:16:25.599855095Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599912 containerd[1400]: time="2025-05-17T00:16:25.599866907Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 17 00:16:25.599912 containerd[1400]: time="2025-05-17T00:16:25.599889379Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 17 00:16:25.599912 containerd[1400]: time="2025-05-17T00:16:25.599903857Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 17 00:16:25.599971 containerd[1400]: time="2025-05-17T00:16:25.599922792Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 17 00:16:25.599971 containerd[1400]: time="2025-05-17T00:16:25.599936187Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 17 00:16:25.599971 containerd[1400]: time="2025-05-17T00:16:25.599950314Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600027 containerd[1400]: time="2025-05-17T00:16:25.599968348Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600027 containerd[1400]: time="2025-05-17T00:16:25.599984398Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600027 containerd[1400]: time="2025-05-17T00:16:25.599999316Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600027 containerd[1400]: time="2025-05-17T00:16:25.600013542Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600100 containerd[1400]: time="2025-05-17T00:16:25.600032338Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600100 containerd[1400]: time="2025-05-17T00:16:25.600045783Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600100 containerd[1400]: time="2025-05-17T00:16:25.600061322Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600100 containerd[1400]: time="2025-05-17T00:16:25.600078524Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600100 containerd[1400]: time="2025-05-17T00:16:25.600096979Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 17 00:16:25.600200 containerd[1400]: time="2025-05-17T00:16:25.600130792Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600200 containerd[1400]: time="2025-05-17T00:16:25.600145109Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600200 containerd[1400]: time="2025-05-17T00:16:25.600160458Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 17 00:16:25.600252 containerd[1400]: time="2025-05-17T00:16:25.600220270Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 17 00:16:25.600252 containerd[1400]: time="2025-05-17T00:16:25.600242351Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 May 17 00:16:25.600290 containerd[1400]: time="2025-05-17T00:16:25.600253803Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 17 00:16:25.600290 containerd[1400]: time="2025-05-17T00:16:25.600272959Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 17 00:16:25.600290 containerd[1400]: time="2025-05-17T00:16:25.600285252Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600350 containerd[1400]: time="2025-05-17T00:16:25.600300320Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 17 00:16:25.600350 containerd[1400]: time="2025-05-17T00:16:25.600311161Z" level=info msg="NRI interface is disabled by configuration." May 17 00:16:25.600350 containerd[1400]: time="2025-05-17T00:16:25.600323414Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 17 00:16:25.600695 containerd[1400]: time="2025-05-17T00:16:25.600631331Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" May 17 00:16:25.600880 containerd[1400]: time="2025-05-17T00:16:25.600700902Z" level=info msg="Connect containerd service" May 17 00:16:25.600880 containerd[1400]: time="2025-05-17T00:16:25.600751426Z" level=info msg="using legacy CRI server" May 17 00:16:25.600880 containerd[1400]: time="2025-05-17T00:16:25.600761706Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 17 00:16:25.601739 containerd[1400]: time="2025-05-17T00:16:25.600993761Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 17 00:16:25.602740 containerd[1400]: time="2025-05-17T00:16:25.602689912Z" 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" May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.602970499Z" level=info msg="Start subscribing containerd event" May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603037805Z" level=info msg="Start recovering state" May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603055688Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603103719Z" level=info msg=serving... address=/run/containerd/containerd.sock May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603129647Z" level=info msg="Start event monitor" May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603150336Z" level=info msg="Start snapshots syncer" May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603163611Z" level=info msg="Start cni network conf syncer for default" May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603174221Z" level=info msg="Start streaming server" May 17 00:16:25.603349 containerd[1400]: time="2025-05-17T00:16:25.603263729Z" level=info msg="containerd successfully booted in 0.042432s" May 17 00:16:25.603340 systemd[1]: Started containerd.service - containerd container runtime. May 17 00:16:25.611395 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 17 00:16:25.628822 systemd[1]: Starting issuegen.service - Generate /run/issue... May 17 00:16:25.637293 systemd[1]: issuegen.service: Deactivated successfully. May 17 00:16:25.637564 systemd[1]: Finished issuegen.service - Generate /run/issue. May 17 00:16:25.640955 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 17 00:16:25.658656 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 17 00:16:25.661584 systemd[1]: Started getty@tty1.service - Getty on tty1. May 17 00:16:25.663699 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 17 00:16:25.665012 systemd[1]: Reached target getty.target - Login Prompts. May 17 00:16:26.131622 systemd-networkd[1333]: eth0: Gained IPv6LL May 17 00:16:26.134880 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 17 00:16:26.137046 systemd[1]: Reached target network-online.target - Network is Online. May 17 00:16:26.147582 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 17 00:16:26.150494 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 17 00:16:26.171602 systemd[1]: coreos-metadata.service: Deactivated successfully. May 17 00:16:26.171859 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 17 00:16:26.173617 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 17 00:16:26.191814 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... May 17 00:16:26.192328 (-wrapper)[1474]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS May 17 00:16:26.193438 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 17 00:16:26.311638 systemd[1]: Starting docker.service - Docker Application Container Engine... May 17 00:16:26.311749 (dockerd)[1484]: 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 May 17 00:16:26.585217 dockerd[1484]: time="2025-05-17T00:16:26.585080025Z" level=info msg="Starting up" May 17 00:16:26.686887 dockerd[1484]: time="2025-05-17T00:16:26.686831542Z" level=info msg="Loading containers: start." May 17 00:16:26.811392 kernel: Initializing XFRM netlink socket May 17 00:16:26.901651 systemd-networkd[1333]: docker0: Link UP May 17 00:16:26.923893 dockerd[1484]: time="2025-05-17T00:16:26.923839091Z" level=info msg="Loading containers: done." May 17 00:16:26.938920 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck2029681361-merged.mount: Deactivated successfully. May 17 00:16:26.940855 dockerd[1484]: time="2025-05-17T00:16:26.940819481Z" 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 May 17 00:16:26.940926 dockerd[1484]: time="2025-05-17T00:16:26.940900914Z" level=info msg="Docker daemon" commit=061aa95809be396a6b5542618d8a34b02a21ff77 containerd-snapshotter=false storage-driver=overlay2 version=26.1.0 May 17 00:16:26.941028 dockerd[1484]: time="2025-05-17T00:16:26.941003987Z" level=info msg="Daemon has completed initialization" May 17 00:16:26.983145 dockerd[1484]: time="2025-05-17T00:16:26.983026229Z" level=info msg="API listen on /run/docker.sock" May 17 00:16:26.985094 systemd[1]: Started docker.service - Docker Application Container Engine. May 17 00:16:26.985915 etcd-wrapper[1478]: Error response from daemon: No such container: etcd-member May 17 00:16:26.999529 etcd-wrapper[1611]: Error response from daemon: No such container: etcd-member May 17 00:16:27.026398 etcd-wrapper[1628]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally May 17 00:16:28.014651 etcd-wrapper[1628]: v3.5.16: Pulling from coreos/etcd May 17 00:16:28.251954 etcd-wrapper[1628]: 804c8aba2cc6: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 2ae710cd8bfe: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: d462aa345367: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 0f8b424aa0b9: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: d557676654e5: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: c8022d07192e: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: d858cbc252ad: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 1069fc2daed1: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: b40161cd83fc: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 5318d93a3a65: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 307c1adadb60: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: fbb01d9e9dc9: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: fbfea02ac3cf: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 8c26e4bf18e2: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 1e59a65f8816: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: ffbd4ca5f0bd: Pulling fs layer May 17 00:16:28.251954 etcd-wrapper[1628]: 5318d93a3a65: Waiting May 17 00:16:28.251954 etcd-wrapper[1628]: 0f8b424aa0b9: Waiting May 17 00:16:28.251954 etcd-wrapper[1628]: d557676654e5: Waiting May 17 00:16:28.251954 etcd-wrapper[1628]: 307c1adadb60: Waiting May 17 00:16:28.251954 etcd-wrapper[1628]: 8c26e4bf18e2: Waiting May 17 00:16:28.251954 etcd-wrapper[1628]: 1e59a65f8816: Waiting May 17 00:16:28.251954 etcd-wrapper[1628]: fbb01d9e9dc9: Waiting May 17 00:16:28.252813 etcd-wrapper[1628]: c8022d07192e: Waiting May 17 00:16:28.252813 etcd-wrapper[1628]: fbfea02ac3cf: Waiting May 17 00:16:28.252813 etcd-wrapper[1628]: d858cbc252ad: Waiting May 17 00:16:28.252813 etcd-wrapper[1628]: 1069fc2daed1: Waiting May 17 00:16:28.252813 etcd-wrapper[1628]: b40161cd83fc: Waiting May 17 00:16:28.252813 etcd-wrapper[1628]: ffbd4ca5f0bd: Waiting May 17 00:16:28.472083 etcd-wrapper[1628]: 2ae710cd8bfe: Verifying Checksum May 17 00:16:28.472083 etcd-wrapper[1628]: 2ae710cd8bfe: Download complete May 17 00:16:28.474494 etcd-wrapper[1628]: 804c8aba2cc6: Verifying Checksum May 17 00:16:28.474494 etcd-wrapper[1628]: 804c8aba2cc6: Download complete May 17 00:16:28.493149 etcd-wrapper[1628]: d462aa345367: Verifying Checksum May 17 00:16:28.493149 etcd-wrapper[1628]: d462aa345367: Download complete May 17 00:16:28.573135 etcd-wrapper[1628]: 804c8aba2cc6: Pull complete May 17 00:16:28.594118 systemd[1]: var-lib-docker-overlay2-f38b559361e37aef0e19a1befc748d76afea4040f27880d4d359ce67c2cdbeb6-merged.mount: Deactivated successfully. May 17 00:16:28.616861 etcd-wrapper[1628]: 2ae710cd8bfe: Pull complete May 17 00:16:28.690270 etcd-wrapper[1628]: 0f8b424aa0b9: Verifying Checksum May 17 00:16:28.690270 etcd-wrapper[1628]: 0f8b424aa0b9: Download complete May 17 00:16:28.691988 etcd-wrapper[1628]: d557676654e5: Verifying Checksum May 17 00:16:28.692055 etcd-wrapper[1628]: d557676654e5: Download complete May 17 00:16:28.792014 etcd-wrapper[1628]: c8022d07192e: Verifying Checksum May 17 00:16:28.792014 etcd-wrapper[1628]: c8022d07192e: Download complete May 17 00:16:28.910114 etcd-wrapper[1628]: d858cbc252ad: Verifying Checksum May 17 00:16:28.910114 etcd-wrapper[1628]: d858cbc252ad: Download complete May 17 00:16:28.939817 etcd-wrapper[1628]: 1069fc2daed1: Verifying Checksum May 17 00:16:28.939817 etcd-wrapper[1628]: 1069fc2daed1: Download complete May 17 00:16:29.010121 etcd-wrapper[1628]: b40161cd83fc: Download complete May 17 00:16:29.056396 systemd[1]: var-lib-docker-overlay2-92fb4561191f341b6bb3be26cc117c300190d141a911cd7e3f94429d1b089f9e-merged.mount: Deactivated successfully. May 17 00:16:29.113428 etcd-wrapper[1628]: d462aa345367: Pull complete May 17 00:16:29.136198 etcd-wrapper[1628]: 5318d93a3a65: Verifying Checksum May 17 00:16:29.136198 etcd-wrapper[1628]: 5318d93a3a65: Download complete May 17 00:16:29.147315 etcd-wrapper[1628]: 0f8b424aa0b9: Pull complete May 17 00:16:29.194720 etcd-wrapper[1628]: 307c1adadb60: Verifying Checksum May 17 00:16:29.194720 etcd-wrapper[1628]: 307c1adadb60: Download complete May 17 00:16:29.256907 etcd-wrapper[1628]: d557676654e5: Pull complete May 17 00:16:29.297210 etcd-wrapper[1628]: c8022d07192e: Pull complete May 17 00:16:29.325707 etcd-wrapper[1628]: fbb01d9e9dc9: Verifying Checksum May 17 00:16:29.325707 etcd-wrapper[1628]: fbb01d9e9dc9: Download complete May 17 00:16:29.341584 etcd-wrapper[1628]: d858cbc252ad: Pull complete May 17 00:16:29.371165 etcd-wrapper[1628]: 1069fc2daed1: Pull complete May 17 00:16:29.483868 etcd-wrapper[1628]: 8c26e4bf18e2: Verifying Checksum May 17 00:16:29.483868 etcd-wrapper[1628]: 8c26e4bf18e2: Download complete May 17 00:16:29.504476 etcd-wrapper[1628]: fbfea02ac3cf: Verifying Checksum May 17 00:16:29.504476 etcd-wrapper[1628]: fbfea02ac3cf: Download complete May 17 00:16:29.551352 etcd-wrapper[1628]: 1e59a65f8816: Verifying Checksum May 17 00:16:29.551352 etcd-wrapper[1628]: 1e59a65f8816: Download complete May 17 00:16:29.586177 etcd-wrapper[1628]: b40161cd83fc: Pull complete May 17 00:16:29.588647 systemd[1]: var-lib-docker-overlay2-397d2bac3364de5faf459641ab722497539bc517440ce261d6fa2638645d55f6-merged.mount: Deactivated successfully. May 17 00:16:29.602511 systemd[1]: var-lib-docker-overlay2-828fc9fc77f7381d465263e01ede461b630e230e92a46233a6e56a126156b02d-merged.mount: Deactivated successfully. May 17 00:16:29.614474 etcd-wrapper[1628]: 5318d93a3a65: Pull complete May 17 00:16:29.631523 systemd[1]: var-lib-docker-overlay2-bc2e79cf1ea81ecbc253339a95990d208d670f208b54d27cb6fdd3855fc9ba01-merged.mount: Deactivated successfully. May 17 00:16:29.646439 etcd-wrapper[1628]: 307c1adadb60: Pull complete May 17 00:16:29.706353 etcd-wrapper[1628]: ffbd4ca5f0bd: Verifying Checksum May 17 00:16:29.706353 etcd-wrapper[1628]: ffbd4ca5f0bd: Download complete May 17 00:16:29.847991 etcd-wrapper[1628]: fbb01d9e9dc9: Pull complete May 17 00:16:30.006330 etcd-wrapper[1628]: fbfea02ac3cf: Pull complete May 17 00:16:30.130380 etcd-wrapper[1628]: 8c26e4bf18e2: Pull complete May 17 00:16:30.158545 etcd-wrapper[1628]: 1e59a65f8816: Pull complete May 17 00:16:30.183704 etcd-wrapper[1628]: ffbd4ca5f0bd: Pull complete May 17 00:16:30.193823 etcd-wrapper[1628]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 May 17 00:16:30.197398 etcd-wrapper[1628]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 May 17 00:16:30.325872 containerd[1400]: time="2025-05-17T00:16:30.325286183Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 17 00:16:30.325872 containerd[1400]: time="2025-05-17T00:16:30.325825845Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 17 00:16:30.325872 containerd[1400]: time="2025-05-17T00:16:30.325838379Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 17 00:16:30.326534 containerd[1400]: time="2025-05-17T00:16:30.325934559Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 17 00:16:30.348578 systemd[1]: Started docker-1ce692f4f979740ec5e6a47a104bd597eb6269ac482ceb7ca167486c1f5358d1.scope - libcontainer container 1ce692f4f979740ec5e6a47a104bd597eb6269ac482ceb7ca167486c1f5358d1. May 17 00:16:30.364798 systemd-resolved[1271]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 17 00:16:30.398683 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.398337Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} May 17 00:16:30.398683 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.398452Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"59eb6637b11f4d21afe4a34c2301b87c"} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:16:30.398585Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:16:30.398600Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:16:30.398605Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:16:30.398610Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:16:30.398629Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.398650Z","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.72:2379"]} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:16:30.398701Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 17 00:16:30.399191 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.398707Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} May 17 00:16:30.399511 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.399160Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} May 17 00:16:30.399511 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.399270Z","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":"59eb6637b11f4d21afe4a34c2301b87c","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.72:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"59eb6637b11f4d21afe4a34c2301b87c=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"} May 17 00:16:30.401174 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.401021Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.407049ms"} May 17 00:16:30.405918 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.405636Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} May 17 00:16:30.405918 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.405701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} May 17 00:16:30.405918 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.405736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} May 17 00:16:30.405918 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.405746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} May 17 00:16:30.405918 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.405754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} May 17 00:16:30.405918 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.405784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 17 00:16:30.410146 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:16:30.409904Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} May 17 00:16:30.411202 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.410997Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} May 17 00:16:30.412201 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.411992Z","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"} May 17 00:16:30.413282 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.413139Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} May 17 00:16:30.413487 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.413318Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 17 00:16:30.413848 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.413627Z","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"} May 17 00:16:30.413848 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.413646Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} May 17 00:16:30.413848 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.413708Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} May 17 00:16:30.413848 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.413754Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} May 17 00:16:30.415820 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.415488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 17 00:16:30.415820 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.415615Z","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"]} May 17 00:16:30.416393 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.416232Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} May 17 00:16:30.416393 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.416252Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} May 17 00:16:30.416494 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:30.416275Z","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.72:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} May 17 00:16:31.407185 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.406770Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} May 17 00:16:31.407185 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.406819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} May 17 00:16:31.407185 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.406835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} May 17 00:16:31.407185 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.406846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} May 17 00:16:31.407185 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.406859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} May 17 00:16:31.407185 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.406871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} May 17 00:16:31.407185 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.406883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} May 17 00:16:31.408026 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.407764Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} May 17 00:16:31.408812 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.408554Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:59eb6637b11f4d21afe4a34c2301b87c ClientURLs:[http://10.0.0.72:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} May 17 00:16:31.408812 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.408566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} May 17 00:16:31.409239 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.408805Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} May 17 00:16:31.409239 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.408938Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} May 17 00:16:31.409239 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.409018Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} May 17 00:16:31.409239 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.409053Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 17 00:16:31.409239 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.409106Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} May 17 00:16:31.409239 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.409135Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} May 17 00:16:31.409687 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:16:31.409445Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} May 17 00:16:31.409672 systemd[1]: Started etcd-member.service - etcd (System Application Container). May 17 00:16:31.410178 systemd[1]: Reached target multi-user.target - Multi-User System. May 17 00:16:31.410557 systemd[1]: Startup finished in 672ms (kernel) + 3.905s (initrd) + 8.116s (userspace) = 12.694s. May 17 00:16:32.465148 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 17 00:16:32.466376 systemd[1]: Started sshd@0-10.0.0.72:22-10.0.0.1:36448.service - OpenSSH per-connection server daemon (10.0.0.1:36448). May 17 00:16:32.506014 sshd[1771]: Accepted publickey for core from 10.0.0.1 port 36448 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:32.507950 sshd[1771]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:32.517687 systemd-logind[1388]: New session 1 of user core. May 17 00:16:32.519003 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 17 00:16:32.531615 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 17 00:16:32.543662 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 17 00:16:32.555583 systemd[1]: Starting user@500.service - User Manager for UID 500... May 17 00:16:32.558698 (systemd)[1775]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 17 00:16:32.679905 systemd[1775]: Queued start job for default target default.target. May 17 00:16:32.689748 systemd[1775]: Created slice app.slice - User Application Slice. May 17 00:16:32.689778 systemd[1775]: Reached target paths.target - Paths. May 17 00:16:32.689795 systemd[1775]: Reached target timers.target - Timers. May 17 00:16:32.691435 systemd[1775]: Starting dbus.socket - D-Bus User Message Bus Socket... May 17 00:16:32.703340 systemd[1775]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 17 00:16:32.703532 systemd[1775]: Reached target sockets.target - Sockets. May 17 00:16:32.703557 systemd[1775]: Reached target basic.target - Basic System. May 17 00:16:32.703604 systemd[1775]: Reached target default.target - Main User Target. May 17 00:16:32.703652 systemd[1775]: Startup finished in 137ms. May 17 00:16:32.703925 systemd[1]: Started user@500.service - User Manager for UID 500. May 17 00:16:32.705465 systemd[1]: Started session-1.scope - Session 1 of User core. May 17 00:16:32.770668 systemd[1]: Started sshd@1-10.0.0.72:22-10.0.0.1:36464.service - OpenSSH per-connection server daemon (10.0.0.1:36464). May 17 00:16:32.818564 sshd[1786]: Accepted publickey for core from 10.0.0.1 port 36464 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:32.820801 sshd[1786]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:32.825965 systemd-logind[1388]: New session 2 of user core. May 17 00:16:32.835662 systemd[1]: Started session-2.scope - Session 2 of User core. May 17 00:16:32.890582 sshd[1786]: pam_unix(sshd:session): session closed for user core May 17 00:16:32.908248 systemd[1]: sshd@1-10.0.0.72:22-10.0.0.1:36464.service: Deactivated successfully. May 17 00:16:32.909875 systemd[1]: session-2.scope: Deactivated successfully. May 17 00:16:32.911294 systemd-logind[1388]: Session 2 logged out. Waiting for processes to exit. May 17 00:16:32.912644 systemd[1]: Started sshd@2-10.0.0.72:22-10.0.0.1:36472.service - OpenSSH per-connection server daemon (10.0.0.1:36472). May 17 00:16:32.913422 systemd-logind[1388]: Removed session 2. May 17 00:16:32.944075 sshd[1793]: Accepted publickey for core from 10.0.0.1 port 36472 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:32.945852 sshd[1793]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:32.950542 systemd-logind[1388]: New session 3 of user core. May 17 00:16:32.960486 systemd[1]: Started session-3.scope - Session 3 of User core. May 17 00:16:33.009774 sshd[1793]: pam_unix(sshd:session): session closed for user core May 17 00:16:33.022324 systemd[1]: sshd@2-10.0.0.72:22-10.0.0.1:36472.service: Deactivated successfully. May 17 00:16:33.024010 systemd[1]: session-3.scope: Deactivated successfully. May 17 00:16:33.025381 systemd-logind[1388]: Session 3 logged out. Waiting for processes to exit. May 17 00:16:33.034660 systemd[1]: Started sshd@3-10.0.0.72:22-10.0.0.1:36480.service - OpenSSH per-connection server daemon (10.0.0.1:36480). May 17 00:16:33.035629 systemd-logind[1388]: Removed session 3. May 17 00:16:33.060389 sshd[1800]: Accepted publickey for core from 10.0.0.1 port 36480 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:33.061749 sshd[1800]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:33.065459 systemd-logind[1388]: New session 4 of user core. May 17 00:16:33.076478 systemd[1]: Started session-4.scope - Session 4 of User core. May 17 00:16:33.129282 sshd[1800]: pam_unix(sshd:session): session closed for user core May 17 00:16:33.141738 systemd[1]: sshd@3-10.0.0.72:22-10.0.0.1:36480.service: Deactivated successfully. May 17 00:16:33.143175 systemd[1]: session-4.scope: Deactivated successfully. May 17 00:16:33.144413 systemd-logind[1388]: Session 4 logged out. Waiting for processes to exit. May 17 00:16:33.145512 systemd[1]: Started sshd@4-10.0.0.72:22-10.0.0.1:36488.service - OpenSSH per-connection server daemon (10.0.0.1:36488). May 17 00:16:33.146220 systemd-logind[1388]: Removed session 4. May 17 00:16:33.174805 sshd[1807]: Accepted publickey for core from 10.0.0.1 port 36488 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:33.176184 sshd[1807]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:33.179756 systemd-logind[1388]: New session 5 of user core. May 17 00:16:33.190469 systemd[1]: Started session-5.scope - Session 5 of User core. May 17 00:16:33.247852 sudo[1810]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 17 00:16:33.248270 sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 17 00:16:33.272771 sudo[1810]: pam_unix(sudo:session): session closed for user root May 17 00:16:33.275270 sshd[1807]: pam_unix(sshd:session): session closed for user core May 17 00:16:33.286912 systemd[1]: sshd@4-10.0.0.72:22-10.0.0.1:36488.service: Deactivated successfully. May 17 00:16:33.288375 systemd[1]: session-5.scope: Deactivated successfully. May 17 00:16:33.289734 systemd-logind[1388]: Session 5 logged out. Waiting for processes to exit. May 17 00:16:33.299654 systemd[1]: Started sshd@5-10.0.0.72:22-10.0.0.1:36492.service - OpenSSH per-connection server daemon (10.0.0.1:36492). May 17 00:16:33.300647 systemd-logind[1388]: Removed session 5. May 17 00:16:33.325085 sshd[1815]: Accepted publickey for core from 10.0.0.1 port 36492 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:33.326542 sshd[1815]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:33.329950 systemd-logind[1388]: New session 6 of user core. May 17 00:16:33.340463 systemd[1]: Started session-6.scope - Session 6 of User core. May 17 00:16:33.392882 sudo[1819]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 17 00:16:33.393204 sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 17 00:16:33.396546 sudo[1819]: pam_unix(sudo:session): session closed for user root May 17 00:16:33.402047 sudo[1818]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules May 17 00:16:33.402352 sudo[1818]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 17 00:16:33.420572 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... May 17 00:16:33.422141 auditctl[1822]: No rules May 17 00:16:33.423330 systemd[1]: audit-rules.service: Deactivated successfully. May 17 00:16:33.423643 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. May 17 00:16:33.425322 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... May 17 00:16:33.452581 augenrules[1840]: No rules May 17 00:16:33.454149 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. May 17 00:16:33.455312 sudo[1818]: pam_unix(sudo:session): session closed for user root May 17 00:16:33.457074 sshd[1815]: pam_unix(sshd:session): session closed for user core May 17 00:16:33.469731 systemd[1]: sshd@5-10.0.0.72:22-10.0.0.1:36492.service: Deactivated successfully. May 17 00:16:33.471075 systemd[1]: session-6.scope: Deactivated successfully. May 17 00:16:33.472303 systemd-logind[1388]: Session 6 logged out. Waiting for processes to exit. May 17 00:16:33.473353 systemd[1]: Started sshd@6-10.0.0.72:22-10.0.0.1:36500.service - OpenSSH per-connection server daemon (10.0.0.1:36500). May 17 00:16:33.474108 systemd-logind[1388]: Removed session 6. May 17 00:16:33.502771 sshd[1848]: Accepted publickey for core from 10.0.0.1 port 36500 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:33.504078 sshd[1848]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:33.507720 systemd-logind[1388]: New session 7 of user core. May 17 00:16:33.519477 systemd[1]: Started session-7.scope - Session 7 of User core. May 17 00:16:33.568641 sshd[1848]: pam_unix(sshd:session): session closed for user core May 17 00:16:33.575862 systemd[1]: sshd@6-10.0.0.72:22-10.0.0.1:36500.service: Deactivated successfully. May 17 00:16:33.577284 systemd[1]: session-7.scope: Deactivated successfully. May 17 00:16:33.578932 systemd-logind[1388]: Session 7 logged out. Waiting for processes to exit. May 17 00:16:33.583586 systemd[1]: Started sshd@7-10.0.0.72:22-10.0.0.1:36506.service - OpenSSH per-connection server daemon (10.0.0.1:36506). May 17 00:16:33.584494 systemd-logind[1388]: Removed session 7. May 17 00:16:33.610275 sshd[1857]: Accepted publickey for core from 10.0.0.1 port 36506 ssh2: RSA SHA256:c3VV2VNpTq6yK4xIFAKH91htkzN8ZWEjxH2QxISCLFU May 17 00:16:33.612172 sshd[1857]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 17 00:16:33.616202 systemd-logind[1388]: New session 8 of user core. May 17 00:16:33.622496 systemd[1]: Started session-8.scope - Session 8 of User core. May 17 00:16:33.760201 sshd[1857]: pam_unix(sshd:session): session closed for user core May 17 00:16:33.763597 systemd[1]: sshd@7-10.0.0.72:22-10.0.0.1:36506.service: Deactivated successfully. May 17 00:16:33.765164 systemd[1]: session-8.scope: Deactivated successfully. May 17 00:16:33.765690 systemd-logind[1388]: Session 8 logged out. Waiting for processes to exit. May 17 00:16:33.766441 systemd-logind[1388]: Removed session 8. May 17 00:17:10.748881 update_engine[1391]: I20250517 00:17:10.748788 1391 update_attempter.cc:509] Updating boot flags... May 17 00:17:10.807406 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1875) May 17 00:17:10.838468 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1873) May 17 00:17:10.884441 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1873) May 17 00:17:31.424106 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:31.423776Z","caller":"traceutil/trace.go:171","msg":"trace[1026781399] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"136.366768ms","start":"2025-05-17T00:17:31.287397Z","end":"2025-05-17T00:17:31.423764Z","steps":["trace[1026781399] 'process raft request' (duration: 53.725005ms)","trace[1026781399] 'compare' (duration: 82.513282ms)"],"step_count":2} May 17 00:17:31.424106 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:31.423776Z","caller":"traceutil/trace.go:171","msg":"trace[2113800515] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"110.750554ms","start":"2025-05-17T00:17:31.313012Z","end":"2025-05-17T00:17:31.423763Z","steps":["trace[2113800515] 'process raft request' (duration: 110.719335ms)"],"step_count":1} May 17 00:17:31.944739 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:31.944378Z","caller":"traceutil/trace.go:171","msg":"trace[231466685] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"106.443215ms","start":"2025-05-17T00:17:31.837892Z","end":"2025-05-17T00:17:31.944335Z","steps":["trace[231466685] 'process raft request' (duration: 90.881389ms)","trace[231466685] 'compare' (duration: 15.40963ms)"],"step_count":2} May 17 00:17:45.375620 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:45.374908Z","caller":"traceutil/trace.go:171","msg":"trace[1265004616] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"120.054014ms","start":"2025-05-17T00:17:45.254826Z","end":"2025-05-17T00:17:45.374880Z","steps":["trace[1265004616] 'process raft request' (duration: 37.902129ms)","trace[1265004616] 'compare' (duration: 82.038351ms)"],"step_count":2} May 17 00:17:45.375620 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:45.374949Z","caller":"traceutil/trace.go:171","msg":"trace[1171408751] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"109.156011ms","start":"2025-05-17T00:17:45.265778Z","end":"2025-05-17T00:17:45.374934Z","steps":["trace[1171408751] 'process raft request' (duration: 109.129732ms)"],"step_count":1} May 17 00:17:45.375620 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:45.375048Z","caller":"traceutil/trace.go:171","msg":"trace[1265973489] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"113.40041ms","start":"2025-05-17T00:17:45.261641Z","end":"2025-05-17T00:17:45.375041Z","steps":["trace[1265973489] 'process raft request' (duration: 113.235681ms)"],"step_count":1} May 17 00:17:45.376306 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:45.375104Z","caller":"traceutil/trace.go:171","msg":"trace[284246214] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"114.58831ms","start":"2025-05-17T00:17:45.260487Z","end":"2025-05-17T00:17:45.375075Z","steps":["trace[284246214] 'read index received' (duration: 32.247591ms)","trace[284246214] 'applied index is now lower than readState.Index' (duration: 82.338775ms)"],"step_count":2} May 17 00:17:45.376306 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:45.375228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.723975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3463"} May 17 00:17:45.376306 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:45.375255Z","caller":"traceutil/trace.go:171","msg":"trace[1098949404] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:586; }","duration":"114.775862ms","start":"2025-05-17T00:17:45.260469Z","end":"2025-05-17T00:17:45.375245Z","steps":["trace[1098949404] 'agreement among raft nodes before linearized reading' (duration: 114.679511ms)"],"step_count":1} May 17 00:17:45.376306 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:45.375438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.248908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/tigera-ca-private\" limit:1 ","response":"range_response_count:1 size:3373"} May 17 00:17:45.376306 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:45.375438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.318684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/tigera-uisettingsgroup-getter\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:17:45.376442 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:45.375483Z","caller":"traceutil/trace.go:171","msg":"trace[1112436999] range","detail":"{range_begin:/registry/clusterrolebindings/tigera-uisettingsgroup-getter; range_end:; response_count:0; response_revision:586; }","duration":"114.544569ms","start":"2025-05-17T00:17:45.260930Z","end":"2025-05-17T00:17:45.375475Z","steps":["trace[1112436999] 'agreement among raft nodes before linearized reading' (duration: 114.326829ms)"],"step_count":1} May 17 00:17:45.376442 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:45.375501Z","caller":"traceutil/trace.go:171","msg":"trace[178722803] range","detail":"{range_begin:/registry/secrets/tigera-operator/tigera-ca-private; range_end:; response_count:1; response_revision:586; }","duration":"112.318168ms","start":"2025-05-17T00:17:45.263146Z","end":"2025-05-17T00:17:45.375464Z","steps":["trace[178722803] 'agreement among raft nodes before linearized reading' (duration: 112.190769ms)"],"step_count":1} May 17 00:17:46.060964 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.060583Z","caller":"traceutil/trace.go:171","msg":"trace[617321446] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"122.503096ms","start":"2025-05-17T00:17:45.938062Z","end":"2025-05-17T00:17:46.060565Z","steps":["trace[617321446] 'process raft request' (duration: 106.000121ms)","trace[617321446] 'compare' (duration: 16.395784ms)"],"step_count":2} May 17 00:17:46.060964 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.060621Z","caller":"traceutil/trace.go:171","msg":"trace[930815400] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"106.398035ms","start":"2025-05-17T00:17:45.954213Z","end":"2025-05-17T00:17:46.060611Z","steps":["trace[930815400] 'read index received' (duration: 89.840468ms)","trace[930815400] 'applied index is now lower than readState.Index' (duration: 16.556866ms)"],"step_count":2} May 17 00:17:46.060964 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.060650Z","caller":"traceutil/trace.go:171","msg":"trace[1443757421] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"120.030683ms","start":"2025-05-17T00:17:45.940615Z","end":"2025-05-17T00:17:46.060645Z","steps":["trace[1443757421] 'process raft request' (duration: 119.912281ms)"],"step_count":1} May 17 00:17:46.060964 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.060735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.494928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:880"} May 17 00:17:46.061228 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.060755Z","caller":"traceutil/trace.go:171","msg":"trace[51456423] range","detail":"{range_begin:/registry/services/specs/calico-system/calico-typha; range_end:; response_count:1; response_revision:627; }","duration":"114.53797ms","start":"2025-05-17T00:17:45.946212Z","end":"2025-05-17T00:17:46.060750Z","steps":["trace[51456423] 'agreement among raft nodes before linearized reading' (duration: 114.438924ms)"],"step_count":1} May 17 00:17:46.099345 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.098970Z","caller":"traceutil/trace.go:171","msg":"trace[1840040915] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"111.427333ms","start":"2025-05-17T00:17:45.987518Z","end":"2025-05-17T00:17:46.098946Z","steps":["trace[1840040915] 'process raft request' (duration: 111.323208ms)"],"step_count":1} May 17 00:17:46.242655 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242047Z","caller":"traceutil/trace.go:171","msg":"trace[357518746] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"178.122125ms","start":"2025-05-17T00:17:46.063915Z","end":"2025-05-17T00:17:46.242037Z","steps":["trace[357518746] 'process raft request' (duration: 178.029751ms)"],"step_count":1} May 17 00:17:46.242655 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242054Z","caller":"traceutil/trace.go:171","msg":"trace[1294356516] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"193.968447ms","start":"2025-05-17T00:17:46.048068Z","end":"2025-05-17T00:17:46.242036Z","steps":["trace[1294356516] 'process raft request' (duration: 193.849865ms)"],"step_count":1} May 17 00:17:46.242655 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242089Z","caller":"traceutil/trace.go:171","msg":"trace[1887269070] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:643; }","duration":"181.439094ms","start":"2025-05-17T00:17:46.060640Z","end":"2025-05-17T00:17:46.242080Z","steps":["trace[1887269070] 'read index received' (duration: 38.290553ms)","trace[1887269070] 'applied index is now lower than readState.Index' (duration: 143.147819ms)"],"step_count":2} May 17 00:17:46.242655 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242043Z","caller":"traceutil/trace.go:171","msg":"trace[521292311] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"194.302836ms","start":"2025-05-17T00:17:46.047715Z","end":"2025-05-17T00:17:46.242018Z","steps":["trace[521292311] 'process raft request' (duration: 170.183029ms)","trace[521292311] 'compare' (duration: 23.909411ms)"],"step_count":2} May 17 00:17:46.242655 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.897419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/calico-system/typha-certs\" limit:1 ","response":"range_response_count:1 size:4586"} May 17 00:17:46.242892 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.608885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-node\" limit:1 ","response":"range_response_count:1 size:1903"} May 17 00:17:46.242892 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242190Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.287483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:6670"} May 17 00:17:46.242892 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242199Z","caller":"traceutil/trace.go:171","msg":"trace[1791674891] range","detail":"{range_begin:/registry/secrets/calico-system/typha-certs; range_end:; response_count:1; response_revision:636; }","duration":"195.937324ms","start":"2025-05-17T00:17:46.046256Z","end":"2025-05-17T00:17:46.242193Z","steps":["trace[1791674891] 'agreement among raft nodes before linearized reading' (duration: 195.866721ms)"],"step_count":1} May 17 00:17:46.242892 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242204Z","caller":"traceutil/trace.go:171","msg":"trace[130202903] range","detail":"{range_begin:/registry/clusterroles/calico-node; range_end:; response_count:1; response_revision:636; }","duration":"221.639862ms","start":"2025-05-17T00:17:46.020558Z","end":"2025-05-17T00:17:46.242197Z","steps":["trace[130202903] 'agreement among raft nodes before linearized reading' (duration: 221.571624ms)"],"step_count":1} May 17 00:17:46.242892 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242211Z","caller":"traceutil/trace.go:171","msg":"trace[987372403] range","detail":"{range_begin:/registry/deployments/calico-system/calico-typha; range_end:; response_count:1; response_revision:636; }","duration":"271.316687ms","start":"2025-05-17T00:17:45.970888Z","end":"2025-05-17T00:17:46.242205Z","steps":["trace[987372403] 'agreement among raft nodes before linearized reading' (duration: 271.281ms)"],"step_count":1} May 17 00:17:46.243010 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.785541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/tigerastatuses/management-cluster-connection\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:17:46.243010 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.836657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/tigerastatuses/gatewayapi\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:17:46.243010 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.672132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/tigera-extension-apiserver-auth-access\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:17:46.243010 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242293Z","caller":"traceutil/trace.go:171","msg":"trace[1629199628] range","detail":"{range_begin:/registry/operator.tigera.io/tigerastatuses/gatewayapi; range_end:; response_count:0; response_revision:636; }","duration":"194.864249ms","start":"2025-05-17T00:17:46.047425Z","end":"2025-05-17T00:17:46.242289Z","steps":["trace[1629199628] 'agreement among raft nodes before linearized reading' (duration: 194.84324ms)"],"step_count":1} May 17 00:17:46.243010 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242301Z","caller":"traceutil/trace.go:171","msg":"trace[161445407] range","detail":"{range_begin:/registry/clusterroles/tigera-extension-apiserver-auth-access; range_end:; response_count:0; response_revision:636; }","duration":"285.701327ms","start":"2025-05-17T00:17:45.956591Z","end":"2025-05-17T00:17:46.242292Z","steps":["trace[161445407] 'agreement among raft nodes before linearized reading' (duration: 285.672523ms)"],"step_count":1} May 17 00:17:46.243117 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242310Z","caller":"traceutil/trace.go:171","msg":"trace[737180759] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"175.953984ms","start":"2025-05-17T00:17:46.066351Z","end":"2025-05-17T00:17:46.242305Z","steps":["trace[737180759] 'process raft request' (duration: 175.643852ms)"],"step_count":1} May 17 00:17:46.243117 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.854847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-system/calico-node\" limit:1 ","response":"range_response_count:1 size:525"} May 17 00:17:46.243117 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.242341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.609057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/tigerastatuses/calico-windows\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:17:46.243117 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242345Z","caller":"traceutil/trace.go:171","msg":"trace[293104571] range","detail":"{range_begin:/registry/serviceaccounts/calico-system/calico-node; range_end:; response_count:1; response_revision:636; }","duration":"246.886065ms","start":"2025-05-17T00:17:45.995452Z","end":"2025-05-17T00:17:46.242338Z","steps":["trace[293104571] 'agreement among raft nodes before linearized reading' (duration: 246.830911ms)"],"step_count":1} May 17 00:17:46.243117 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242352Z","caller":"traceutil/trace.go:171","msg":"trace[1739006550] range","detail":"{range_begin:/registry/operator.tigera.io/tigerastatuses/calico-windows; range_end:; response_count:0; response_revision:636; }","duration":"195.630127ms","start":"2025-05-17T00:17:46.046719Z","end":"2025-05-17T00:17:46.242349Z","steps":["trace[1739006550] 'agreement among raft nodes before linearized reading' (duration: 195.615168ms)"],"step_count":1} May 17 00:17:46.243271 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.242293Z","caller":"traceutil/trace.go:171","msg":"trace[2099374991] range","detail":"{range_begin:/registry/operator.tigera.io/tigerastatuses/management-cluster-connection; range_end:; response_count:0; response_revision:636; }","duration":"194.839443ms","start":"2025-05-17T00:17:46.047444Z","end":"2025-05-17T00:17:46.242283Z","steps":["trace[2099374991] 'agreement among raft nodes before linearized reading' (duration: 194.785351ms)"],"step_count":1} May 17 00:17:46.264213 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.263857Z","caller":"traceutil/trace.go:171","msg":"trace[421217084] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"115.791256ms","start":"2025-05-17T00:17:46.148057Z","end":"2025-05-17T00:17:46.263849Z","steps":["trace[421217084] 'process raft request' (duration: 115.765277ms)"],"step_count":1} May 17 00:17:46.264213 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.263912Z","caller":"traceutil/trace.go:171","msg":"trace[2736904] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"161.176028ms","start":"2025-05-17T00:17:46.102721Z","end":"2025-05-17T00:17:46.263897Z","steps":["trace[2736904] 'process raft request' (duration: 161.022801ms)"],"step_count":1} May 17 00:17:46.264296 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.263946Z","caller":"traceutil/trace.go:171","msg":"trace[1970937198] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"161.18223ms","start":"2025-05-17T00:17:46.102759Z","end":"2025-05-17T00:17:46.263941Z","steps":["trace[1970937198] 'process raft request' (duration: 161.039462ms)"],"step_count":1} May 17 00:17:46.264296 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.264009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.673048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-node\" limit:1 ","response":"range_response_count:1 size:692"} May 17 00:17:46.264296 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.264030Z","caller":"traceutil/trace.go:171","msg":"trace[705504882] range","detail":"{range_begin:/registry/clusterrolebindings/calico-node; range_end:; response_count:1; response_revision:639; }","duration":"162.718013ms","start":"2025-05-17T00:17:46.101306Z","end":"2025-05-17T00:17:46.264024Z","steps":["trace[705504882] 'agreement among raft nodes before linearized reading' (duration: 162.655766ms)"],"step_count":1} May 17 00:17:46.264296 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.264057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.430668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/calico-system\" limit:1 ","response":"range_response_count:1 size:764"} May 17 00:17:46.264296 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.264072Z","caller":"traceutil/trace.go:171","msg":"trace[935513248] range","detail":"{range_begin:/registry/namespaces/calico-system; range_end:; response_count:1; response_revision:639; }","duration":"193.455616ms","start":"2025-05-17T00:17:46.070612Z","end":"2025-05-17T00:17:46.264067Z","steps":["trace[935513248] 'agreement among raft nodes before linearized reading' (duration: 193.397977ms)"],"step_count":1} May 17 00:17:46.378147 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.377848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.042314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3463"} May 17 00:17:46.378147 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.377917Z","caller":"traceutil/trace.go:171","msg":"trace[707980530] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:646; }","duration":"105.122484ms","start":"2025-05-17T00:17:46.272785Z","end":"2025-05-17T00:17:46.377907Z","steps":["trace[707980530] 'agreement among raft nodes before linearized reading' (duration: 105.01872ms)"],"step_count":1} May 17 00:17:46.527644 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.527418Z","caller":"traceutil/trace.go:171","msg":"trace[219006929] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"107.027611ms","start":"2025-05-17T00:17:46.420375Z","end":"2025-05-17T00:17:46.527402Z","steps":["trace[219006929] 'read index received' (duration: 97.395817ms)","trace[219006929] 'applied index is now lower than readState.Index' (duration: 9.631163ms)"],"step_count":2} May 17 00:17:46.527644 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.527494Z","caller":"traceutil/trace.go:171","msg":"trace[1735310229] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"121.913812ms","start":"2025-05-17T00:17:46.405573Z","end":"2025-05-17T00:17:46.527487Z","steps":["trace[1735310229] 'process raft request' (duration: 112.187309ms)"],"step_count":1} May 17 00:17:46.527644 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.527561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.920185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-system/calico-typha\" limit:1 ","response":"range_response_count:1 size:441"} May 17 00:17:46.527861 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.527595Z","caller":"traceutil/trace.go:171","msg":"trace[1192266] range","detail":"{range_begin:/registry/serviceaccounts/calico-system/calico-typha; range_end:; response_count:1; response_revision:653; }","duration":"112.969286ms","start":"2025-05-17T00:17:46.414618Z","end":"2025-05-17T00:17:46.527587Z","steps":["trace[1192266] 'agreement among raft nodes before linearized reading' (duration: 112.873937ms)"],"step_count":1} May 17 00:17:46.570339 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.570103Z","caller":"traceutil/trace.go:171","msg":"trace[192111139] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"146.884074ms","start":"2025-05-17T00:17:46.423205Z","end":"2025-05-17T00:17:46.570089Z","steps":["trace[192111139] 'process raft request' (duration: 146.776863ms)"],"step_count":1} May 17 00:17:46.570609 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.570174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.139955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/calico-apiserver/tigera-extension-apiserver-secrets-access\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:17:46.570609 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.570180Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.153817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/whisker-backend-key-pair\" limit:1 ","response":"range_response_count:1 size:4806"} May 17 00:17:46.570609 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.570199Z","caller":"traceutil/trace.go:171","msg":"trace[1154926164] range","detail":"{range_begin:/registry/rolebindings/calico-apiserver/tigera-extension-apiserver-secrets-access; range_end:; response_count:0; response_revision:655; }","duration":"147.172265ms","start":"2025-05-17T00:17:46.423019Z","end":"2025-05-17T00:17:46.570192Z","steps":["trace[1154926164] 'agreement among raft nodes before linearized reading' (duration: 147.120839ms)"],"step_count":1} May 17 00:17:46.570609 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.570204Z","caller":"traceutil/trace.go:171","msg":"trace[1682893085] range","detail":"{range_begin:/registry/secrets/tigera-operator/whisker-backend-key-pair; range_end:; response_count:1; response_revision:655; }","duration":"124.189504ms","start":"2025-05-17T00:17:46.446008Z","end":"2025-05-17T00:17:46.570197Z","steps":["trace[1682893085] 'agreement among raft nodes before linearized reading' (duration: 124.103683ms)"],"step_count":1} May 17 00:17:46.570609 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:17:46.570270Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.322942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/calico-system/cni-config\" limit:1 ","response":"range_response_count:1 size:1179"} May 17 00:17:46.570835 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:46.570291Z","caller":"traceutil/trace.go:171","msg":"trace[1326734655] range","detail":"{range_begin:/registry/configmaps/calico-system/cni-config; range_end:; response_count:1; response_revision:655; }","duration":"149.370412ms","start":"2025-05-17T00:17:46.420914Z","end":"2025-05-17T00:17:46.570285Z","steps":["trace[1326734655] 'agreement among raft nodes before linearized reading' (duration: 149.295771ms)"],"step_count":1} May 17 00:17:52.218135 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:52.217626Z","caller":"traceutil/trace.go:171","msg":"trace[583790483] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"122.284094ms","start":"2025-05-17T00:17:52.095325Z","end":"2025-05-17T00:17:52.217609Z","steps":["trace[583790483] 'process raft request' (duration: 122.235843ms)"],"step_count":1} May 17 00:17:52.218135 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:52.217667Z","caller":"traceutil/trace.go:171","msg":"trace[2053758452] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"125.498086ms","start":"2025-05-17T00:17:52.092153Z","end":"2025-05-17T00:17:52.217651Z","steps":["trace[2053758452] 'process raft request' (duration: 80.088353ms)","trace[2053758452] 'compare' (duration: 45.233421ms)"],"step_count":2} May 17 00:17:52.218135 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:52.217818Z","caller":"traceutil/trace.go:171","msg":"trace[295458081] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"104.881709ms","start":"2025-05-17T00:17:52.112927Z","end":"2025-05-17T00:17:52.217809Z","steps":["trace[295458081] 'process raft request' (duration: 104.824232ms)"],"step_count":1} May 17 00:17:52.218135 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:17:52.217813Z","caller":"traceutil/trace.go:171","msg":"trace[1209435510] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"104.928497ms","start":"2025-05-17T00:17:52.112867Z","end":"2025-05-17T00:17:52.217796Z","steps":["trace[1209435510] 'process raft request' (duration: 104.72251ms)"],"step_count":1} May 17 00:18:05.304323 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:05.303947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.616355ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} May 17 00:18:05.304323 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:05.304004Z","caller":"traceutil/trace.go:171","msg":"trace[1538597679] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"296.423454ms","start":"2025-05-17T00:18:05.007572Z","end":"2025-05-17T00:18:05.303995Z","steps":["trace[1538597679] 'read index received' (duration: 42.680315ms)","trace[1538597679] 'applied index is now lower than readState.Index' (duration: 253.742718ms)"],"step_count":2} May 17 00:18:05.304323 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:05.304054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.466807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:18:05.304323 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:05.304085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.161371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver\" limit:1 ","response":"range_response_count:1 size:971"} May 17 00:18:05.304323 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:05.304095Z","caller":"traceutil/trace.go:171","msg":"trace[751825453] range","detail":"{range_begin:/registry/minions; range_end:; response_count:0; response_revision:879; }","duration":"296.527374ms","start":"2025-05-17T00:18:05.007560Z","end":"2025-05-17T00:18:05.304087Z","steps":["trace[751825453] 'agreement among raft nodes before linearized reading' (duration: 296.467258ms)"],"step_count":1} May 17 00:18:05.305009 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:05.304104Z","caller":"traceutil/trace.go:171","msg":"trace[1347889814] range","detail":"{range_begin:/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver; range_end:; response_count:1; response_revision:879; }","duration":"274.182662ms","start":"2025-05-17T00:18:05.029915Z","end":"2025-05-17T00:18:05.304098Z","steps":["trace[1347889814] 'agreement among raft nodes before linearized reading' (duration: 274.122747ms)"],"step_count":1} May 17 00:18:05.305009 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:05.304165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.090666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/10.0.0.83\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:18:05.305009 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:05.304188Z","caller":"traceutil/trace.go:171","msg":"trace[1238145160] range","detail":"{range_begin:/registry/leases/kube-node-lease/10.0.0.83; range_end:; response_count:0; response_revision:879; }","duration":"175.123759ms","start":"2025-05-17T00:18:05.129059Z","end":"2025-05-17T00:18:05.304183Z","steps":["trace[1238145160] 'agreement among raft nodes before linearized reading' (duration: 175.094193ms)"],"step_count":1} May 17 00:18:05.640032 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:05.639669Z","caller":"traceutil/trace.go:171","msg":"trace[708144152] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"101.44737ms","start":"2025-05-17T00:18:05.538207Z","end":"2025-05-17T00:18:05.639654Z","steps":["trace[708144152] 'process raft request' (duration: 90.129444ms)","trace[708144152] 'compare' (duration: 11.134303ms)"],"step_count":2} May 17 00:18:11.536437 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:11.536043Z","caller":"traceutil/trace.go:171","msg":"trace[1564522920] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"113.508505ms","start":"2025-05-17T00:18:11.422511Z","end":"2025-05-17T00:18:11.536020Z","steps":["trace[1564522920] 'process raft request' (duration: 113.408914ms)"],"step_count":1} May 17 00:18:12.231076 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.230703Z","caller":"traceutil/trace.go:171","msg":"trace[1150350729] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"110.7089ms","start":"2025-05-17T00:18:12.119980Z","end":"2025-05-17T00:18:12.230689Z","steps":["trace[1150350729] 'process raft request' (duration: 65.431366ms)","trace[1150350729] 'compare' (duration: 45.215836ms)"],"step_count":2} May 17 00:18:12.231076 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.230776Z","caller":"traceutil/trace.go:171","msg":"trace[1124049576] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"101.760968ms","start":"2025-05-17T00:18:12.129005Z","end":"2025-05-17T00:18:12.230766Z","steps":["trace[1124049576] 'read index received' (duration: 56.393663ms)","trace[1124049576] 'applied index is now lower than readState.Index' (duration: 45.365532ms)"],"step_count":2} May 17 00:18:12.231076 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.230816Z","caller":"traceutil/trace.go:171","msg":"trace[2100110860] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"109.481812ms","start":"2025-05-17T00:18:12.121325Z","end":"2025-05-17T00:18:12.230806Z","steps":["trace[2100110860] 'process raft request' (duration: 109.345862ms)"],"step_count":1} May 17 00:18:12.231076 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.230876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.708188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/calico-apiserver/calico-apiserver-6b579c9866-2q49j.1840286703bb4b3a\" limit:1 ","response":"range_response_count:1 size:793"} May 17 00:18:12.231314 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.230902Z","caller":"traceutil/trace.go:171","msg":"trace[1204124738] range","detail":"{range_begin:/registry/events/calico-apiserver/calico-apiserver-6b579c9866-2q49j.1840286703bb4b3a; range_end:; response_count:1; response_revision:1004; }","duration":"101.765638ms","start":"2025-05-17T00:18:12.129127Z","end":"2025-05-17T00:18:12.230893Z","steps":["trace[1204124738] 'agreement among raft nodes before linearized reading' (duration: 101.685064ms)"],"step_count":1} May 17 00:18:12.231314 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.230908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.894825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/calico-system/calico-node\" limit:1 ","response":"range_response_count:1 size:11398"} May 17 00:18:12.231314 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.230940Z","caller":"traceutil/trace.go:171","msg":"trace[185653] range","detail":"{range_begin:/registry/daemonsets/calico-system/calico-node; range_end:; response_count:1; response_revision:1004; }","duration":"101.942487ms","start":"2025-05-17T00:18:12.128993Z","end":"2025-05-17T00:18:12.230935Z","steps":["trace[185653] 'agreement among raft nodes before linearized reading' (duration: 101.8136ms)"],"step_count":1} May 17 00:18:12.724919 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.724611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.586021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 17 00:18:12.725496 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.724760Z","caller":"traceutil/trace.go:171","msg":"trace[2141320449] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"493.236715ms","start":"2025-05-17T00:18:12.231509Z","end":"2025-05-17T00:18:12.724746Z","steps":["trace[2141320449] 'process raft request' (duration: 262.235579ms)","trace[2141320449] 'compare' (duration: 230.499897ms)"],"step_count":2} May 17 00:18:12.725496 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.724791Z","caller":"traceutil/trace.go:171","msg":"trace[1612846292] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"457.314643ms","start":"2025-05-17T00:18:12.267469Z","end":"2025-05-17T00:18:12.724783Z","steps":["trace[1612846292] 'read index received' (duration: 226.271808ms)","trace[1612846292] 'applied index is now lower than readState.Index' (duration: 231.042053ms)"],"step_count":2} May 17 00:18:12.725496 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.724826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:12.231502Z","time spent":"493.295438ms","remote":"10.0.0.75:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6259,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:12.725496 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.724814Z","caller":"traceutil/trace.go:171","msg":"trace[438640235] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"492.534071ms","start":"2025-05-17T00:18:12.232253Z","end":"2025-05-17T00:18:12.724787Z","steps":["trace[438640235] 'process raft request' (duration: 492.420403ms)"],"step_count":1} May 17 00:18:12.725632 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.724925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.455752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/ipamhandles/k8s-pod-network.e36a7fdaee10fd496b338d3a325a2bdc5add71782e4bb453272ec952a16f0d9b\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:18:12.725632 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.724949Z","caller":"traceutil/trace.go:171","msg":"trace[2122665364] range","detail":"{range_begin:/registry/crd.projectcalico.org/ipamhandles/k8s-pod-network.e36a7fdaee10fd496b338d3a325a2bdc5add71782e4bb453272ec952a16f0d9b; range_end:; response_count:0; response_revision:1008; }","duration":"457.493175ms","start":"2025-05-17T00:18:12.267447Z","end":"2025-05-17T00:18:12.724940Z","steps":["trace[2122665364] 'agreement among raft nodes before linearized reading' (duration: 457.457978ms)"],"step_count":1} May 17 00:18:12.725632 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.724970Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:12.267438Z","time spent":"457.524184ms","remote":"10.0.0.75:58676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/crd.projectcalico.org/ipamhandles/k8s-pod-network.e36a7fdaee10fd496b338d3a325a2bdc5add71782e4bb453272ec952a16f0d9b\" limit:1 "} May 17 00:18:12.725720 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.724964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:12.232246Z","time spent":"492.635104ms","remote":"10.0.0.75:33638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":778,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:12.725720 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.725002Z","caller":"traceutil/trace.go:171","msg":"trace[1362663850] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"320.667201ms","start":"2025-05-17T00:18:12.404330Z","end":"2025-05-17T00:18:12.724997Z","steps":["trace[1362663850] 'process raft request' (duration: 320.377498ms)"],"step_count":1} May 17 00:18:12.725720 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.725000Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.842037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/calico-system/csi-node-driver-nqgh6.18402868ecd25705\" limit:1 ","response":"range_response_count:1 size:868"} May 17 00:18:12.725720 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.725023Z","caller":"traceutil/trace.go:171","msg":"trace[650974079] range","detail":"{range_begin:/registry/events/calico-system/csi-node-driver-nqgh6.18402868ecd25705; range_end:; response_count:1; response_revision:1008; }","duration":"279.878517ms","start":"2025-05-17T00:18:12.445137Z","end":"2025-05-17T00:18:12.725016Z","steps":["trace[650974079] 'agreement among raft nodes before linearized reading' (duration: 279.806208ms)"],"step_count":1} May 17 00:18:12.725823 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:12.725029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:12.404320Z","time spent":"320.691999ms","remote":"10.0.0.75:33810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:12.725823 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:12.725058Z","caller":"traceutil/trace.go:171","msg":"trace[700822678] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"270.31527ms","start":"2025-05-17T00:18:12.454738Z","end":"2025-05-17T00:18:12.725054Z","steps":["trace[700822678] 'process raft request' (duration: 270.025876ms)"],"step_count":1} May 17 00:18:13.133330 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:13.132924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.590764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 17 00:18:13.133330 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:13.132990Z","caller":"traceutil/trace.go:171","msg":"trace[1999015754] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"349.083575ms","start":"2025-05-17T00:18:12.783897Z","end":"2025-05-17T00:18:13.132980Z","steps":["trace[1999015754] 'read index received' (duration: 127.358134ms)","trace[1999015754] 'applied index is now lower than readState.Index' (duration: 221.72477ms)"],"step_count":2} May 17 00:18:13.133330 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:13.133021Z","caller":"traceutil/trace.go:171","msg":"trace[881453047] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"353.936783ms","start":"2025-05-17T00:18:12.779070Z","end":"2025-05-17T00:18:13.133007Z","steps":["trace[881453047] 'process raft request' (duration: 132.202305ms)","trace[881453047] 'compare' (duration: 221.51585ms)"],"step_count":2} May 17 00:18:13.133330 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:13.133074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.176484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/ipamhandles/k8s-pod-network.a680f72ad133e3f5c4befd0f44ca8f9dab942e7882bc302556c2eec21ee9df1b\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:18:13.133588 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:13.133112Z","caller":"traceutil/trace.go:171","msg":"trace[1867010995] range","detail":"{range_begin:/registry/crd.projectcalico.org/ipamhandles/k8s-pod-network.a680f72ad133e3f5c4befd0f44ca8f9dab942e7882bc302556c2eec21ee9df1b; range_end:; response_count:0; response_revision:1010; }","duration":"349.249905ms","start":"2025-05-17T00:18:12.783852Z","end":"2025-05-17T00:18:13.133102Z","steps":["trace[1867010995] 'agreement among raft nodes before linearized reading' (duration: 349.180762ms)"],"step_count":1} May 17 00:18:13.133588 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:13.133132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:12.783839Z","time spent":"349.285362ms","remote":"10.0.0.75:58676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/crd.projectcalico.org/ipamhandles/k8s-pod-network.a680f72ad133e3f5c4befd0f44ca8f9dab942e7882bc302556c2eec21ee9df1b\" limit:1 "} May 17 00:18:13.133588 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:13.133078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:12.779062Z","time spent":"353.989142ms","remote":"10.0.0.75:33638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":809,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:14.095941 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.095576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.002988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 17 00:18:14.095941 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.095686Z","caller":"traceutil/trace.go:171","msg":"trace[1762013108] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"272.282581ms","start":"2025-05-17T00:18:13.823393Z","end":"2025-05-17T00:18:14.095675Z","steps":["trace[1762013108] 'process raft request' (duration: 272.252774ms)"],"step_count":1} May 17 00:18:14.095941 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.095744Z","caller":"traceutil/trace.go:171","msg":"trace[893856400] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"311.472928ms","start":"2025-05-17T00:18:13.784257Z","end":"2025-05-17T00:18:14.095730Z","steps":["trace[893856400] 'process raft request' (duration: 126.268804ms)","trace[893856400] 'compare' (duration: 184.925361ms)"],"step_count":2} May 17 00:18:14.096487 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.095794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:13.784226Z","time spent":"311.54224ms","remote":"10.0.0.75:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6439,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:14.420386 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.419964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.562489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-apiserver-delegate-auth\" limit:1 ","response":"range_response_count:1 size:661"} May 17 00:18:14.420386 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.420017Z","caller":"traceutil/trace.go:171","msg":"trace[1152660580] range","detail":"{range_begin:/registry/clusterrolebindings/calico-apiserver-delegate-auth; range_end:; response_count:1; response_revision:1038; }","duration":"223.653795ms","start":"2025-05-17T00:18:14.196349Z","end":"2025-05-17T00:18:14.420003Z","steps":["trace[1152660580] 'range keys from in-memory index tree' (duration: 223.435648ms)"],"step_count":1} May 17 00:18:14.782847 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.782308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.912211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 17 00:18:14.782847 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.782400Z","caller":"traceutil/trace.go:171","msg":"trace[2052994291] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"333.211337ms","start":"2025-05-17T00:18:14.449173Z","end":"2025-05-17T00:18:14.782384Z","steps":["trace[2052994291] 'process raft request' (duration: 88.176141ms)","trace[2052994291] 'compare' (duration: 244.851815ms)"],"step_count":2} May 17 00:18:14.782847 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.782454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:14.449165Z","time spent":"333.261924ms","remote":"10.0.0.75:33752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5826,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:14.783082 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.782477Z","caller":"traceutil/trace.go:171","msg":"trace[1830982363] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"325.222516ms","start":"2025-05-17T00:18:14.457248Z","end":"2025-05-17T00:18:14.782471Z","steps":["trace[1830982363] 'read index received' (duration: 80.068774ms)","trace[1830982363] 'applied index is now lower than readState.Index' (duration: 245.153271ms)"],"step_count":2} May 17 00:18:14.783082 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.782496Z","caller":"traceutil/trace.go:171","msg":"trace[1161691015] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"294.662394ms","start":"2025-05-17T00:18:14.487823Z","end":"2025-05-17T00:18:14.782485Z","steps":["trace[1161691015] 'process raft request' (duration: 294.584064ms)"],"step_count":1} May 17 00:18:14.783082 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.782551Z","caller":"traceutil/trace.go:171","msg":"trace[2051313130] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"324.352845ms","start":"2025-05-17T00:18:14.458190Z","end":"2025-05-17T00:18:14.782542Z","steps":["trace[2051313130] 'process raft request' (duration: 324.186427ms)"],"step_count":1} May 17 00:18:14.783082 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.782601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:14.458182Z","time spent":"324.394084ms","remote":"10.0.0.75:33638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:14.783189 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.782608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.18358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-apiserver/calico-apiserver\" limit:1 ","response":"range_response_count:1 size:452"} May 17 00:18:14.783189 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.782635Z","caller":"traceutil/trace.go:171","msg":"trace[227307241] range","detail":"{range_begin:/registry/serviceaccounts/calico-apiserver/calico-apiserver; range_end:; response_count:1; response_revision:1043; }","duration":"337.217085ms","start":"2025-05-17T00:18:14.445410Z","end":"2025-05-17T00:18:14.782627Z","steps":["trace[227307241] 'agreement among raft nodes before linearized reading' (duration: 337.094119ms)"],"step_count":1} May 17 00:18:14.783189 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.782669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:14.445405Z","time spent":"337.244486ms","remote":"10.0.0.75:33770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":476,"request content":"key:\"/registry/serviceaccounts/calico-apiserver/calico-apiserver\" limit:1 "} May 17 00:18:14.831170 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.830981Z","caller":"traceutil/trace.go:171","msg":"trace[151057513] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"100.55772ms","start":"2025-05-17T00:18:14.730412Z","end":"2025-05-17T00:18:14.830970Z","steps":["trace[151057513] 'process raft request' (duration: 100.439253ms)"],"step_count":1} May 17 00:18:14.831394 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.831025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.564919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} May 17 00:18:14.831394 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.831064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.92281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/calico-node-txcd5\" limit:1 ","response":"range_response_count:1 size:14294"} May 17 00:18:14.831394 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.831078Z","caller":"traceutil/trace.go:171","msg":"trace[493788365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"161.641655ms","start":"2025-05-17T00:18:14.669424Z","end":"2025-05-17T00:18:14.831066Z","steps":["trace[493788365] 'agreement among raft nodes before linearized reading' (duration: 161.550361ms)"],"step_count":1} May 17 00:18:14.831394 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.831084Z","caller":"traceutil/trace.go:171","msg":"trace[1840292858] range","detail":"{range_begin:/registry/pods/calico-system/calico-node-txcd5; range_end:; response_count:1; response_revision:1044; }","duration":"370.952715ms","start":"2025-05-17T00:18:14.460125Z","end":"2025-05-17T00:18:14.831078Z","steps":["trace[1840292858] 'agreement among raft nodes before linearized reading' (duration: 370.894805ms)"],"step_count":1} May 17 00:18:14.831394 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.831266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:14.460120Z","time spent":"371.136258ms","remote":"10.0.0.75:33752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":14318,"request content":"key:\"/registry/pods/calico-system/calico-node-txcd5\" limit:1 "} May 17 00:18:14.997771 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.997493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.243252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 17 00:18:14.997970 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.997569Z","caller":"traceutil/trace.go:171","msg":"trace[113323594] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"153.496604ms","start":"2025-05-17T00:18:14.844061Z","end":"2025-05-17T00:18:14.997557Z","steps":["trace[113323594] 'read index received' (duration: 42.134505ms)","trace[113323594] 'applied index is now lower than readState.Index' (duration: 111.361368ms)"],"step_count":2} May 17 00:18:14.997970 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.997605Z","caller":"traceutil/trace.go:171","msg":"trace[1723832321] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"156.862549ms","start":"2025-05-17T00:18:14.840718Z","end":"2025-05-17T00:18:14.997581Z","steps":["trace[1723832321] 'process raft request' (duration: 45.478699ms)","trace[1723832321] 'compare' (duration: 111.133241ms)"],"step_count":2} May 17 00:18:14.997970 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:14.997651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.587267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/calico-apiserver/calico-api\" limit:1 ","response":"range_response_count:1 size:858"} May 17 00:18:14.997970 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:14.997665Z","caller":"traceutil/trace.go:171","msg":"trace[1695843435] range","detail":"{range_begin:/registry/services/specs/calico-apiserver/calico-api; range_end:; response_count:1; response_revision:1047; }","duration":"153.611776ms","start":"2025-05-17T00:18:14.844048Z","end":"2025-05-17T00:18:14.997660Z","steps":["trace[1695843435] 'agreement among raft nodes before linearized reading' (duration: 153.536471ms)"],"step_count":1} May 17 00:18:15.020831 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.020572Z","caller":"traceutil/trace.go:171","msg":"trace[344563175] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"173.686016ms","start":"2025-05-17T00:18:14.846873Z","end":"2025-05-17T00:18:15.020559Z","steps":["trace[344563175] 'process raft request' (duration: 173.524539ms)"],"step_count":1} May 17 00:18:15.240215 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:15.239961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.744381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} May 17 00:18:15.240712 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.240109Z","caller":"traceutil/trace.go:171","msg":"trace[1362154382] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"216.202457ms","start":"2025-05-17T00:18:15.023893Z","end":"2025-05-17T00:18:15.240096Z","steps":["trace[1362154382] 'read index received' (duration: 3.325392ms)","trace[1362154382] 'applied index is now lower than readState.Index' (duration: 212.875652ms)"],"step_count":2} May 17 00:18:15.240712 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.240146Z","caller":"traceutil/trace.go:171","msg":"trace[45008110] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"214.538148ms","start":"2025-05-17T00:18:15.025602Z","end":"2025-05-17T00:18:15.240140Z","steps":["trace[45008110] 'process raft request' (duration: 214.512649ms)"],"step_count":1} May 17 00:18:15.240712 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:15.240200Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.302369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/calico-apiserver/allow-apiserver\" limit:1 ","response":"range_response_count:1 size:601"} May 17 00:18:15.240712 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.240217Z","caller":"traceutil/trace.go:171","msg":"trace[1742638808] range","detail":"{range_begin:/registry/networkpolicies/calico-apiserver/allow-apiserver; range_end:; response_count:1; response_revision:1051; }","duration":"216.337255ms","start":"2025-05-17T00:18:15.023875Z","end":"2025-05-17T00:18:15.240213Z","steps":["trace[1742638808] 'agreement among raft nodes before linearized reading' (duration: 216.256921ms)"],"step_count":1} May 17 00:18:15.240808 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.240242Z","caller":"traceutil/trace.go:171","msg":"trace[793893700] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"215.91603ms","start":"2025-05-17T00:18:15.024321Z","end":"2025-05-17T00:18:15.240237Z","steps":["trace[793893700] 'process raft request' (duration: 215.693294ms)"],"step_count":1} May 17 00:18:15.240808 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.240267Z","caller":"traceutil/trace.go:171","msg":"trace[1487297360] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"215.717772ms","start":"2025-05-17T00:18:15.024544Z","end":"2025-05-17T00:18:15.240262Z","steps":["trace[1487297360] 'process raft request' (duration: 215.546004ms)"],"step_count":1} May 17 00:18:15.713221 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:15.712898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.2496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} May 17 00:18:15.713221 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.712980Z","caller":"traceutil/trace.go:171","msg":"trace[316405118] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"222.957153ms","start":"2025-05-17T00:18:15.490011Z","end":"2025-05-17T00:18:15.712968Z","steps":["trace[316405118] 'read index received' (duration: 67.598695ms)","trace[316405118] 'applied index is now lower than readState.Index' (duration: 155.357627ms)"],"step_count":2} May 17 00:18:15.713221 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.713027Z","caller":"traceutil/trace.go:171","msg":"trace[656608785] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"223.715303ms","start":"2025-05-17T00:18:15.489300Z","end":"2025-05-17T00:18:15.713015Z","steps":["trace[656608785] 'process raft request' (duration: 68.299995ms)","trace[656608785] 'compare' (duration: 155.175729ms)"],"step_count":2} May 17 00:18:15.713511 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:15.713102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.080278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/calico-webhook-reader\" limit:1 ","response":"range_response_count:1 size:602"} May 17 00:18:15.713511 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.713127Z","caller":"traceutil/trace.go:171","msg":"trace[898730000] range","detail":"{range_begin:/registry/clusterroles/calico-webhook-reader; range_end:; response_count:1; response_revision:1055; }","duration":"223.11805ms","start":"2025-05-17T00:18:15.490002Z","end":"2025-05-17T00:18:15.713120Z","steps":["trace[898730000] 'agreement among raft nodes before linearized reading' (duration: 222.994865ms)"],"step_count":1} May 17 00:18:15.735544 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.735284Z","caller":"traceutil/trace.go:171","msg":"trace[1456241562] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"241.650257ms","start":"2025-05-17T00:18:15.493626Z","end":"2025-05-17T00:18:15.735277Z","steps":["trace[1456241562] 'process raft request' (duration: 241.589311ms)"],"step_count":1} May 17 00:18:15.936871 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.936527Z","caller":"traceutil/trace.go:171","msg":"trace[221327287] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"142.526131ms","start":"2025-05-17T00:18:15.793983Z","end":"2025-05-17T00:18:15.936509Z","steps":["trace[221327287] 'read index received' (duration: 78.919513ms)","trace[221327287] 'applied index is now lower than readState.Index' (duration: 63.605235ms)"],"step_count":2} May 17 00:18:15.936871 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.936544Z","caller":"traceutil/trace.go:171","msg":"trace[1530288164] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"143.709713ms","start":"2025-05-17T00:18:15.792818Z","end":"2025-05-17T00:18:15.936528Z","steps":["trace[1530288164] 'process raft request' (duration: 80.13205ms)","trace[1530288164] 'compare' (duration: 63.468485ms)"],"step_count":2} May 17 00:18:15.936871 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:15.936611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.615101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver\" limit:1 ","response":"range_response_count:1 size:971"} May 17 00:18:15.936871 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:15.936636Z","caller":"traceutil/trace.go:171","msg":"trace[551690270] range","detail":"{range_begin:/registry/poddisruptionbudgets/calico-apiserver/calico-apiserver; range_end:; response_count:1; response_revision:1058; }","duration":"142.658595ms","start":"2025-05-17T00:18:15.793971Z","end":"2025-05-17T00:18:15.936630Z","steps":["trace[551690270] 'agreement among raft nodes before linearized reading' (duration: 142.590685ms)"],"step_count":1} May 17 00:18:16.193179 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:16.192855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.894932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-apiserver-webhook-reader\" limit:1 ","response":"range_response_count:1 size:663"} May 17 00:18:16.193179 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:16.192914Z","caller":"traceutil/trace.go:171","msg":"trace[1476854723] range","detail":"{range_begin:/registry/clusterrolebindings/calico-apiserver-webhook-reader; range_end:; response_count:1; response_revision:1058; }","duration":"153.97802ms","start":"2025-05-17T00:18:16.038924Z","end":"2025-05-17T00:18:16.192902Z","steps":["trace[1476854723] 'range keys from in-memory index tree' (duration: 153.796283ms)"],"step_count":1} May 17 00:18:18.612882 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:18.612566Z","caller":"traceutil/trace.go:171","msg":"trace[546969421] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"157.100578ms","start":"2025-05-17T00:18:18.455448Z","end":"2025-05-17T00:18:18.612549Z","steps":["trace[546969421] 'read index received' (duration: 106.618748ms)","trace[546969421] 'applied index is now lower than readState.Index' (duration: 50.481319ms)"],"step_count":2} May 17 00:18:18.612882 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:18.612584Z","caller":"traceutil/trace.go:171","msg":"trace[1561689871] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1071; }","duration":"160.602369ms","start":"2025-05-17T00:18:18.451967Z","end":"2025-05-17T00:18:18.612569Z","steps":["trace[1561689871] 'process raft request' (duration: 110.098897ms)","trace[1561689871] 'compare' (duration: 50.444981ms)"],"step_count":2} May 17 00:18:18.613399 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:18.612700Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.665023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/whisker-8df58fb98-g274p\" limit:1 ","response":"range_response_count:1 size:6498"} May 17 00:18:18.613399 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:18.612727Z","caller":"traceutil/trace.go:171","msg":"trace[43291365] range","detail":"{range_begin:/registry/pods/calico-system/whisker-8df58fb98-g274p; range_end:; response_count:1; response_revision:1071; }","duration":"158.713376ms","start":"2025-05-17T00:18:18.454008Z","end":"2025-05-17T00:18:18.612722Z","steps":["trace[43291365] 'agreement among raft nodes before linearized reading' (duration: 158.599097ms)"],"step_count":1} May 17 00:18:18.613399 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:18.612700Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.581855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/calico-apiserver/calico-apiserver-certs\" limit:1 ","response":"range_response_count:1 size:4753"} May 17 00:18:18.613399 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:18.612758Z","caller":"traceutil/trace.go:171","msg":"trace[1853251312] range","detail":"{range_begin:/registry/secrets/calico-apiserver/calico-apiserver-certs; range_end:; response_count:1; response_revision:1071; }","duration":"154.670782ms","start":"2025-05-17T00:18:18.458076Z","end":"2025-05-17T00:18:18.612746Z","steps":["trace[1853251312] 'agreement among raft nodes before linearized reading' (duration: 154.55965ms)"],"step_count":1} May 17 00:18:21.617868 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:21.617632Z","caller":"traceutil/trace.go:171","msg":"trace[1584502496] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"105.233302ms","start":"2025-05-17T00:18:21.512380Z","end":"2025-05-17T00:18:21.617614Z","steps":["trace[1584502496] 'process raft request' (duration: 84.906552ms)","trace[1584502496] 'compare' (duration: 20.279961ms)"],"step_count":2} May 17 00:18:24.596223 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:24.595884Z","caller":"traceutil/trace.go:171","msg":"trace[1025413220] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"108.434402ms","start":"2025-05-17T00:18:24.487433Z","end":"2025-05-17T00:18:24.595868Z","steps":["trace[1025413220] 'process raft request' (duration: 108.362565ms)"],"step_count":1} May 17 00:18:24.780000 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:24.779731Z","caller":"traceutil/trace.go:171","msg":"trace[509784966] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"142.337046ms","start":"2025-05-17T00:18:24.637378Z","end":"2025-05-17T00:18:24.779715Z","steps":["trace[509784966] 'process raft request' (duration: 74.12247ms)","trace[509784966] 'compare' (duration: 68.1277ms)"],"step_count":2} May 17 00:18:24.981806 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:24.981232Z","caller":"traceutil/trace.go:171","msg":"trace[1919799099] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"110.905849ms","start":"2025-05-17T00:18:24.870309Z","end":"2025-05-17T00:18:24.981215Z","steps":["trace[1919799099] 'process raft request' (duration: 110.861364ms)"],"step_count":1} May 17 00:18:24.981806 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:24.981292Z","caller":"traceutil/trace.go:171","msg":"trace[707573550] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"151.749011ms","start":"2025-05-17T00:18:24.829530Z","end":"2025-05-17T00:18:24.981279Z","steps":["trace[707573550] 'process raft request' (duration: 147.766593ms)"],"step_count":1} May 17 00:18:29.743928 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:29.743639Z","caller":"traceutil/trace.go:171","msg":"trace[1833300672] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"159.430885ms","start":"2025-05-17T00:18:29.584186Z","end":"2025-05-17T00:18:29.743617Z","steps":["trace[1833300672] 'process raft request' (duration: 95.944263ms)","trace[1833300672] 'compare' (duration: 63.415476ms)"],"step_count":2} May 17 00:18:45.146789 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:45.146449Z","caller":"traceutil/trace.go:171","msg":"trace[1794499700] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"113.627942ms","start":"2025-05-17T00:18:45.032804Z","end":"2025-05-17T00:18:45.146432Z","steps":["trace[1794499700] 'process raft request' (duration: 82.839985ms)","trace[1794499700] 'compare' (duration: 30.646139ms)"],"step_count":2} May 17 00:18:49.680736 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:49.680376Z","caller":"traceutil/trace.go:171","msg":"trace[1647186722] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"534.570716ms","start":"2025-05-17T00:18:49.145772Z","end":"2025-05-17T00:18:49.680342Z","steps":["trace[1647186722] 'process raft request' (duration: 534.46757ms)"],"step_count":1} May 17 00:18:49.680736 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:49.680497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:49.145763Z","time spent":"534.672437ms","remote":"10.0.0.75:41666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:49.704033 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:49.703749Z","caller":"traceutil/trace.go:171","msg":"trace[679531085] linearizableReadLoop","detail":"{readStateIndex:1530; appliedIndex:1528; }","duration":"143.542653ms","start":"2025-05-17T00:18:49.560194Z","end":"2025-05-17T00:18:49.703737Z","steps":["trace[679531085] 'read index received' (duration: 120.077067ms)","trace[679531085] 'applied index is now lower than readState.Index' (duration: 23.465045ms)"],"step_count":2} May 17 00:18:49.704033 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:49.703796Z","caller":"traceutil/trace.go:171","msg":"trace[1889423566] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"533.521581ms","start":"2025-05-17T00:18:49.170267Z","end":"2025-05-17T00:18:49.703788Z","steps":["trace[1889423566] 'process raft request' (duration: 533.418375ms)"],"step_count":1} May 17 00:18:49.704237 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:49.703839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.6466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/10.0.0.75\" limit:1 ","response":"range_response_count:1 size:125"} May 17 00:18:49.704237 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:49.703858Z","caller":"traceutil/trace.go:171","msg":"trace[695753030] range","detail":"{range_begin:/registry/masterleases/10.0.0.75; range_end:; response_count:1; response_revision:1348; }","duration":"143.697135ms","start":"2025-05-17T00:18:49.560156Z","end":"2025-05-17T00:18:49.703853Z","steps":["trace[695753030] 'agreement among raft nodes before linearized reading' (duration: 143.637452ms)"],"step_count":1} May 17 00:18:49.704237 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:49.703865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-17T00:18:49.170249Z","time spent":"533.572356ms","remote":"10.0.0.75:33810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 17 00:18:50.401296 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:50.400907Z","caller":"traceutil/trace.go:171","msg":"trace[828720413] linearizableReadLoop","detail":"{readStateIndex:1534; appliedIndex:1534; }","duration":"209.961557ms","start":"2025-05-17T00:18:50.190929Z","end":"2025-05-17T00:18:50.400891Z","steps":["trace[828720413] 'read index received' (duration: 209.954705ms)","trace[828720413] 'applied index is now lower than readState.Index' (duration: 5.63µs)"],"step_count":2} May 17 00:18:50.401296 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:50.400907Z","caller":"traceutil/trace.go:171","msg":"trace[733954538] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"244.211721ms","start":"2025-05-17T00:18:50.156679Z","end":"2025-05-17T00:18:50.400891Z","steps":["trace[733954538] 'process raft request' (duration: 244.117583ms)"],"step_count":1} May 17 00:18:50.401296 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:50.401098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.151217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-extension-apiserver-auth-access\" limit:1 ","response":"range_response_count:1 size:694"} May 17 00:18:50.401296 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:50.401131Z","caller":"traceutil/trace.go:171","msg":"trace[1499832331] range","detail":"{range_begin:/registry/clusterrolebindings/calico-extension-apiserver-auth-access; range_end:; response_count:1; response_revision:1351; }","duration":"210.206021ms","start":"2025-05-17T00:18:50.190916Z","end":"2025-05-17T00:18:50.401122Z","steps":["trace[1499832331] 'agreement among raft nodes before linearized reading' (duration: 210.038343ms)"],"step_count":1} May 17 00:18:50.780969 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:50.780550Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.480737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/tigera-uisettingsgroup-getter\" limit:1 ","response":"range_response_count:0 size:5"} May 17 00:18:50.780969 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:50.780610Z","caller":"traceutil/trace.go:171","msg":"trace[1020554146] range","detail":"{range_begin:/registry/clusterroles/tigera-uisettingsgroup-getter; range_end:; response_count:0; response_revision:1352; }","duration":"289.565067ms","start":"2025-05-17T00:18:50.491034Z","end":"2025-05-17T00:18:50.780599Z","steps":["trace[1020554146] 'range keys from in-memory index tree' (duration: 289.422357ms)"],"step_count":1} May 17 00:18:53.765100 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:53.764514Z","caller":"traceutil/trace.go:171","msg":"trace[929272983] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"129.595705ms","start":"2025-05-17T00:18:53.634893Z","end":"2025-05-17T00:18:53.764489Z","steps":["trace[929272983] 'process raft request' (duration: 60.191621ms)","trace[929272983] 'compare' (duration: 69.237438ms)"],"step_count":2} May 17 00:18:53.765100 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:53.764556Z","caller":"traceutil/trace.go:171","msg":"trace[1956457334] linearizableReadLoop","detail":"{readStateIndex:1555; appliedIndex:1552; }","duration":"110.474627ms","start":"2025-05-17T00:18:53.654076Z","end":"2025-05-17T00:18:53.764550Z","steps":["trace[1956457334] 'read index received' (duration: 40.975404ms)","trace[1956457334] 'applied index is now lower than readState.Index' (duration: 69.498742ms)"],"step_count":2} May 17 00:18:53.765100 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:53.764534Z","caller":"traceutil/trace.go:171","msg":"trace[735143732] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"129.595204ms","start":"2025-05-17T00:18:53.634931Z","end":"2025-05-17T00:18:53.764526Z","steps":["trace[735143732] 'process raft request' (duration: 129.503391ms)"],"step_count":1} May 17 00:18:53.765100 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:53.764594Z","caller":"traceutil/trace.go:171","msg":"trace[1372727174] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"127.863711ms","start":"2025-05-17T00:18:53.636699Z","end":"2025-05-17T00:18:53.764563Z","steps":["trace[1372727174] 'process raft request' (duration: 127.767339ms)"],"step_count":1} May 17 00:18:53.765100 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:53.764734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.648856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/tiers/adminnetworkpolicy\" limit:1 ","response":"range_response_count:1 size:721"} May 17 00:18:53.765675 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:53.764760Z","caller":"traceutil/trace.go:171","msg":"trace[1488659674] range","detail":"{range_begin:/registry/crd.projectcalico.org/tiers/adminnetworkpolicy; range_end:; response_count:1; response_revision:1371; }","duration":"110.706625ms","start":"2025-05-17T00:18:53.654047Z","end":"2025-05-17T00:18:53.764754Z","steps":["trace[1488659674] 'agreement among raft nodes before linearized reading' (duration: 110.542354ms)"],"step_count":1} May 17 00:18:58.284841 etcd-wrapper[1628]: {"level":"warn","ts":"2025-05-17T00:18:58.284525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.210739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/tiers/\" range_end:\"/registry/crd.projectcalico.org/tiers0\" count_only:true ","response":"range_response_count:0 size:7"} May 17 00:18:58.284841 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:58.284570Z","caller":"traceutil/trace.go:171","msg":"trace[882293291] range","detail":"{range_begin:/registry/crd.projectcalico.org/tiers/; range_end:/registry/crd.projectcalico.org/tiers0; response_count:0; response_revision:1382; }","duration":"132.287765ms","start":"2025-05-17T00:18:58.152273Z","end":"2025-05-17T00:18:58.284561Z","steps":["trace[882293291] 'count revisions from in-memory index tree' (duration: 132.158821ms)"],"step_count":1} May 17 00:18:58.553069 etcd-wrapper[1628]: {"level":"info","ts":"2025-05-17T00:18:58.552667Z","caller":"traceutil/trace.go:171","msg":"trace[921196326] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"100.801354ms","start":"2025-05-17T00:18:58.451843Z","end":"2025-05-17T00:18:58.552644Z","steps":["trace[921196326] 'process raft request' (duration: 36.648098ms)","trace[921196326] 'compare' (duration: 64.027869ms)"],"step_count":2}