May 10 10:01:41.918390 kernel: Linux version 6.6.89-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sat May 10 08:33:52 -00 2025 May 10 10:01:41.918417 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=cdff7a1e66558670c3a31fd90f395811dccc4cb131ce51930f033b8634f7f080 May 10 10:01:41.918426 kernel: BIOS-provided physical RAM map: May 10 10:01:41.918433 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable May 10 10:01:41.918440 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved May 10 10:01:41.918446 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved May 10 10:01:41.918454 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable May 10 10:01:41.918476 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved May 10 10:01:41.918483 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved May 10 10:01:41.918490 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved May 10 10:01:41.918496 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 10 10:01:41.918503 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved May 10 10:01:41.918510 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 10 10:01:41.918517 kernel: NX (Execute Disable) protection: active May 10 10:01:41.918527 kernel: APIC: Static calls initialized May 10 10:01:41.918535 kernel: SMBIOS 2.8 present. May 10 10:01:41.918542 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 May 10 10:01:41.918549 kernel: Hypervisor detected: KVM May 10 10:01:41.918557 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 10 10:01:41.918564 kernel: kvm-clock: using sched offset of 4171847864 cycles May 10 10:01:41.918571 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 10 10:01:41.918587 kernel: tsc: Detected 2794.748 MHz processor May 10 10:01:41.918594 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 10 10:01:41.918605 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 10 10:01:41.918613 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 May 10 10:01:41.918620 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs May 10 10:01:41.918628 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 10 10:01:41.918636 kernel: Using GB pages for direct mapping May 10 10:01:41.918643 kernel: ACPI: Early table checksum verification disabled May 10 10:01:41.918651 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) May 10 10:01:41.918658 kernel: ACPI: RSDT 0x000000009CFE2408 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:01:41.918668 kernel: ACPI: FACP 0x000000009CFE21E8 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:01:41.918699 kernel: ACPI: DSDT 0x000000009CFE0040 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:01:41.918707 kernel: ACPI: FACS 0x000000009CFE0000 000040 May 10 10:01:41.918714 kernel: ACPI: APIC 0x000000009CFE22DC 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:01:41.918722 kernel: ACPI: HPET 0x000000009CFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:01:41.918729 kernel: ACPI: MCFG 0x000000009CFE23A4 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:01:41.918736 kernel: ACPI: WAET 0x000000009CFE23E0 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:01:41.918744 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21e8-0x9cfe22db] May 10 10:01:41.918752 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21e7] May 10 10:01:41.918765 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] May 10 10:01:41.918773 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22dc-0x9cfe236b] May 10 10:01:41.918780 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe236c-0x9cfe23a3] May 10 10:01:41.918788 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23a4-0x9cfe23df] May 10 10:01:41.918796 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23e0-0x9cfe2407] May 10 10:01:41.918806 kernel: No NUMA configuration found May 10 10:01:41.918814 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] May 10 10:01:41.918821 kernel: NODE_DATA(0) allocated [mem 0x9cfd4000-0x9cfdbfff] May 10 10:01:41.918829 kernel: Zone ranges: May 10 10:01:41.918837 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 10 10:01:41.918845 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] May 10 10:01:41.918852 kernel: Normal empty May 10 10:01:41.918860 kernel: Device empty May 10 10:01:41.918867 kernel: Movable zone start for each node May 10 10:01:41.918875 kernel: Early memory node ranges May 10 10:01:41.918885 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] May 10 10:01:41.918893 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] May 10 10:01:41.918900 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] May 10 10:01:41.918908 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 10 10:01:41.918915 kernel: On node 0, zone DMA: 97 pages in unavailable ranges May 10 10:01:41.918923 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges May 10 10:01:41.918931 kernel: ACPI: PM-Timer IO Port: 0x608 May 10 10:01:41.918939 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 10 10:01:41.918946 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 10 10:01:41.918956 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 10 10:01:41.918964 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 10 10:01:41.918972 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 10 10:01:41.918979 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 10 10:01:41.918987 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 10 10:01:41.918994 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 10 10:01:41.919002 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 10 10:01:41.919010 kernel: TSC deadline timer available May 10 10:01:41.919017 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 10 10:01:41.919027 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 10 10:01:41.919035 kernel: kvm-guest: KVM setup pv remote TLB flush May 10 10:01:41.919042 kernel: kvm-guest: setup PV sched yield May 10 10:01:41.919050 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices May 10 10:01:41.919058 kernel: Booting paravirtualized kernel on KVM May 10 10:01:41.919066 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 10 10:01:41.919073 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 10 10:01:41.919081 kernel: percpu: Embedded 58 pages/cpu s197416 r8192 d31960 u524288 May 10 10:01:41.919089 kernel: pcpu-alloc: s197416 r8192 d31960 u524288 alloc=1*2097152 May 10 10:01:41.919099 kernel: pcpu-alloc: [0] 0 1 2 3 May 10 10:01:41.919106 kernel: kvm-guest: PV spinlocks enabled May 10 10:01:41.919114 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 10 10:01:41.919123 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=cdff7a1e66558670c3a31fd90f395811dccc4cb131ce51930f033b8634f7f080 May 10 10:01:41.919131 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 10 10:01:41.919139 kernel: random: crng init done May 10 10:01:41.919146 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 10 10:01:41.919154 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 10 10:01:41.919161 kernel: Fallback order for Node 0: 0 May 10 10:01:41.919172 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 May 10 10:01:41.919189 kernel: Policy zone: DMA32 May 10 10:01:41.919205 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 10 10:01:41.919222 kernel: Memory: 2436632K/2571752K available (14336K kernel code, 2309K rwdata, 9044K rodata, 53680K init, 1596K bss, 134860K reserved, 0K cma-reserved) May 10 10:01:41.919244 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 10 10:01:41.919260 kernel: ftrace: allocating 38190 entries in 150 pages May 10 10:01:41.919275 kernel: ftrace: allocated 150 pages with 4 groups May 10 10:01:41.919291 kernel: Dynamic Preempt: voluntary May 10 10:01:41.919299 kernel: rcu: Preemptible hierarchical RCU implementation. May 10 10:01:41.919311 kernel: rcu: RCU event tracing is enabled. May 10 10:01:41.919319 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 10 10:01:41.919326 kernel: Trampoline variant of Tasks RCU enabled. May 10 10:01:41.919334 kernel: Rude variant of Tasks RCU enabled. May 10 10:01:41.919342 kernel: Tracing variant of Tasks RCU enabled. May 10 10:01:41.919350 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 10 10:01:41.919357 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 10 10:01:41.919365 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 May 10 10:01:41.919373 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 10 10:01:41.919383 kernel: Console: colour VGA+ 80x25 May 10 10:01:41.919390 kernel: printk: console [ttyS0] enabled May 10 10:01:41.919399 kernel: ACPI: Core revision 20230628 May 10 10:01:41.919408 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 10 10:01:41.919417 kernel: APIC: Switch to symmetric I/O mode setup May 10 10:01:41.919426 kernel: x2apic enabled May 10 10:01:41.919434 kernel: APIC: Switched APIC routing to: physical x2apic May 10 10:01:41.919442 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() May 10 10:01:41.919450 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() May 10 10:01:41.919468 kernel: kvm-guest: setup PV IPIs May 10 10:01:41.919476 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 10 10:01:41.919484 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized May 10 10:01:41.919494 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) May 10 10:01:41.919502 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 10 10:01:41.919512 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 May 10 10:01:41.919523 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 May 10 10:01:41.919533 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 10 10:01:41.919543 kernel: Spectre V2 : Mitigation: Retpolines May 10 10:01:41.919556 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 10 10:01:41.919567 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls May 10 10:01:41.919585 kernel: RETBleed: Mitigation: untrained return thunk May 10 10:01:41.919594 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 10 10:01:41.919602 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 10 10:01:41.919611 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! May 10 10:01:41.919619 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. May 10 10:01:41.919631 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode May 10 10:01:41.919639 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 10 10:01:41.919647 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 10 10:01:41.919655 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 10 10:01:41.919663 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 10 10:01:41.919671 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. May 10 10:01:41.919692 kernel: Freeing SMP alternatives memory: 32K May 10 10:01:41.919700 kernel: pid_max: default: 32768 minimum: 301 May 10 10:01:41.919708 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 10 10:01:41.919719 kernel: landlock: Up and running. May 10 10:01:41.919727 kernel: SELinux: Initializing. May 10 10:01:41.919735 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 10 10:01:41.919743 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 10 10:01:41.919751 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) May 10 10:01:41.919759 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 10 10:01:41.919767 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 10 10:01:41.919775 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 10 10:01:41.919786 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. May 10 10:01:41.919794 kernel: ... version: 0 May 10 10:01:41.919802 kernel: ... bit width: 48 May 10 10:01:41.919810 kernel: ... generic registers: 6 May 10 10:01:41.919817 kernel: ... value mask: 0000ffffffffffff May 10 10:01:41.919825 kernel: ... max period: 00007fffffffffff May 10 10:01:41.919833 kernel: ... fixed-purpose events: 0 May 10 10:01:41.919841 kernel: ... event mask: 000000000000003f May 10 10:01:41.919850 kernel: signal: max sigframe size: 1776 May 10 10:01:41.919858 kernel: rcu: Hierarchical SRCU implementation. May 10 10:01:41.919868 kernel: rcu: Max phase no-delay instances is 400. May 10 10:01:41.919876 kernel: smp: Bringing up secondary CPUs ... May 10 10:01:41.919884 kernel: smpboot: x86: Booting SMP configuration: May 10 10:01:41.919892 kernel: .... node #0, CPUs: #1 #2 #3 May 10 10:01:41.919900 kernel: smp: Brought up 1 node, 4 CPUs May 10 10:01:41.919908 kernel: smpboot: Max logical packages: 1 May 10 10:01:41.919916 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) May 10 10:01:41.919924 kernel: devtmpfs: initialized May 10 10:01:41.919932 kernel: x86/mm: Memory block size: 128MB May 10 10:01:41.919943 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 10 10:01:41.919951 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 10 10:01:41.919959 kernel: pinctrl core: initialized pinctrl subsystem May 10 10:01:41.919967 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 10 10:01:41.919975 kernel: audit: initializing netlink subsys (disabled) May 10 10:01:41.919983 kernel: audit: type=2000 audit(1746871298.389:1): state=initialized audit_enabled=0 res=1 May 10 10:01:41.919991 kernel: thermal_sys: Registered thermal governor 'step_wise' May 10 10:01:41.919999 kernel: thermal_sys: Registered thermal governor 'user_space' May 10 10:01:41.920007 kernel: cpuidle: using governor menu May 10 10:01:41.920017 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 10 10:01:41.920025 kernel: dca service started, version 1.12.1 May 10 10:01:41.920033 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) May 10 10:01:41.920041 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry May 10 10:01:41.920049 kernel: PCI: Using configuration type 1 for base access May 10 10:01:41.920057 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 10 10:01:41.920065 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 10 10:01:41.920073 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 10 10:01:41.920081 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 10 10:01:41.920092 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 10 10:01:41.920100 kernel: ACPI: Added _OSI(Module Device) May 10 10:01:41.920108 kernel: ACPI: Added _OSI(Processor Device) May 10 10:01:41.920116 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 10 10:01:41.920124 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 10 10:01:41.920132 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 10 10:01:41.920140 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 10 10:01:41.920147 kernel: ACPI: Interpreter enabled May 10 10:01:41.920155 kernel: ACPI: PM: (supports S0 S3 S5) May 10 10:01:41.920166 kernel: ACPI: Using IOAPIC for interrupt routing May 10 10:01:41.920174 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 10 10:01:41.920182 kernel: PCI: Using E820 reservations for host bridge windows May 10 10:01:41.920190 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F May 10 10:01:41.920198 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 10 10:01:41.920475 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 10 10:01:41.920646 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] May 10 10:01:41.920886 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] May 10 10:01:41.920902 kernel: PCI host bridge to bus 0000:00 May 10 10:01:41.921052 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 10 10:01:41.921174 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 10 10:01:41.921298 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 10 10:01:41.921417 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] May 10 10:01:41.921536 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] May 10 10:01:41.921670 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] May 10 10:01:41.921828 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 10 10:01:41.922032 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 May 10 10:01:41.922201 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 May 10 10:01:41.922334 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] May 10 10:01:41.922469 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] May 10 10:01:41.922609 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] May 10 10:01:41.922764 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 10 10:01:41.922931 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 May 10 10:01:41.923144 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] May 10 10:01:41.923307 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] May 10 10:01:41.923459 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] May 10 10:01:41.923622 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 May 10 10:01:41.923780 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] May 10 10:01:41.923943 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] May 10 10:01:41.924127 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] May 10 10:01:41.924314 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 10 10:01:41.924448 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] May 10 10:01:41.924590 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] May 10 10:01:41.924745 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] May 10 10:01:41.924885 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] May 10 10:01:41.925055 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 May 10 10:01:41.925191 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO May 10 10:01:41.925338 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 May 10 10:01:41.925475 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] May 10 10:01:41.925615 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] May 10 10:01:41.925786 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 May 10 10:01:41.925926 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] May 10 10:01:41.925938 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 10 10:01:41.925955 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 10 10:01:41.925963 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 10 10:01:41.925971 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 10 10:01:41.925979 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 May 10 10:01:41.925987 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 May 10 10:01:41.925995 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 May 10 10:01:41.926003 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 May 10 10:01:41.926016 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 May 10 10:01:41.926024 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 May 10 10:01:41.926032 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 May 10 10:01:41.926040 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 May 10 10:01:41.926048 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 May 10 10:01:41.926056 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 May 10 10:01:41.926064 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 May 10 10:01:41.926072 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 May 10 10:01:41.926080 kernel: iommu: Default domain type: Translated May 10 10:01:41.926090 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 10 10:01:41.926098 kernel: PCI: Using ACPI for IRQ routing May 10 10:01:41.926106 kernel: PCI: pci_cache_line_size set to 64 bytes May 10 10:01:41.926114 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] May 10 10:01:41.926122 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] May 10 10:01:41.926262 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device May 10 10:01:41.926393 kernel: pci 0000:00:01.0: vgaarb: bridge control possible May 10 10:01:41.926522 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 10 10:01:41.926537 kernel: vgaarb: loaded May 10 10:01:41.926545 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 10 10:01:41.926553 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 10 10:01:41.926561 kernel: clocksource: Switched to clocksource kvm-clock May 10 10:01:41.926569 kernel: VFS: Disk quotas dquot_6.6.0 May 10 10:01:41.926586 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 10 10:01:41.926594 kernel: pnp: PnP ACPI init May 10 10:01:41.926767 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved May 10 10:01:41.926785 kernel: pnp: PnP ACPI: found 6 devices May 10 10:01:41.926793 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 10 10:01:41.926801 kernel: NET: Registered PF_INET protocol family May 10 10:01:41.926809 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 10 10:01:41.926818 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 10 10:01:41.926830 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 10 10:01:41.926843 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 10 10:01:41.926851 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 10 10:01:41.926859 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 10 10:01:41.926871 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 10 10:01:41.926879 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 10 10:01:41.926887 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 10 10:01:41.926895 kernel: NET: Registered PF_XDP protocol family May 10 10:01:41.927021 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 10 10:01:41.927141 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 10 10:01:41.927260 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 10 10:01:41.927380 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] May 10 10:01:41.927504 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] May 10 10:01:41.927644 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] May 10 10:01:41.927656 kernel: PCI: CLS 0 bytes, default 64 May 10 10:01:41.927664 kernel: Initialise system trusted keyrings May 10 10:01:41.927673 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 10 10:01:41.927698 kernel: Key type asymmetric registered May 10 10:01:41.927706 kernel: Asymmetric key parser 'x509' registered May 10 10:01:41.927714 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 10 10:01:41.927723 kernel: io scheduler mq-deadline registered May 10 10:01:41.927735 kernel: io scheduler kyber registered May 10 10:01:41.927744 kernel: io scheduler bfq registered May 10 10:01:41.927752 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 10 10:01:41.927761 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 May 10 10:01:41.927769 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 May 10 10:01:41.927778 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 May 10 10:01:41.927786 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 10 10:01:41.927794 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 10 10:01:41.927803 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 10 10:01:41.927814 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 10 10:01:41.927822 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 10 10:01:41.927986 kernel: rtc_cmos 00:04: RTC can wake from S4 May 10 10:01:41.927998 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 10 10:01:41.928120 kernel: rtc_cmos 00:04: registered as rtc0 May 10 10:01:41.928243 kernel: rtc_cmos 00:04: setting system clock to 2025-05-10T10:01:41 UTC (1746871301) May 10 10:01:41.928367 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs May 10 10:01:41.928378 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled May 10 10:01:41.928390 kernel: NET: Registered PF_INET6 protocol family May 10 10:01:41.928398 kernel: Segment Routing with IPv6 May 10 10:01:41.928406 kernel: In-situ OAM (IOAM) with IPv6 May 10 10:01:41.928414 kernel: NET: Registered PF_PACKET protocol family May 10 10:01:41.928423 kernel: Key type dns_resolver registered May 10 10:01:41.928430 kernel: IPI shorthand broadcast: enabled May 10 10:01:41.928439 kernel: sched_clock: Marking stable (3298005477, 108452800)->(3427778619, -21320342) May 10 10:01:41.928447 kernel: registered taskstats version 1 May 10 10:01:41.928455 kernel: Loading compiled-in X.509 certificates May 10 10:01:41.928466 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: f8080549509982706805ea0b811f8f4bcb4a274e' May 10 10:01:41.928474 kernel: Key type .fscrypt registered May 10 10:01:41.928482 kernel: Key type fscrypt-provisioning registered May 10 10:01:41.928490 kernel: ima: No TPM chip found, activating TPM-bypass! May 10 10:01:41.928498 kernel: ima: Allocated hash algorithm: sha1 May 10 10:01:41.928506 kernel: ima: No architecture policies found May 10 10:01:41.928514 kernel: clk: Disabling unused clocks May 10 10:01:41.928522 kernel: Warning: unable to open an initial console. May 10 10:01:41.928531 kernel: Freeing unused kernel image (initmem) memory: 53680K May 10 10:01:41.928541 kernel: Write protecting the kernel read-only data: 24576k May 10 10:01:41.928550 kernel: Freeing unused kernel image (rodata/data gap) memory: 1196K May 10 10:01:41.928558 kernel: Run /init as init process May 10 10:01:41.928566 kernel: with arguments: May 10 10:01:41.928574 kernel: /init May 10 10:01:41.928591 kernel: with environment: May 10 10:01:41.928599 kernel: HOME=/ May 10 10:01:41.928607 kernel: TERM=linux May 10 10:01:41.928615 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 10 10:01:41.928627 systemd[1]: Successfully made /usr/ read-only. May 10 10:01:41.928639 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 10 10:01:41.928648 systemd[1]: Detected virtualization kvm. May 10 10:01:41.928657 systemd[1]: Detected architecture x86-64. May 10 10:01:41.928665 systemd[1]: Running in initrd. May 10 10:01:41.928685 systemd[1]: No hostname configured, using default hostname. May 10 10:01:41.928703 systemd[1]: Hostname set to . May 10 10:01:41.928724 systemd[1]: Initializing machine ID from VM UUID. May 10 10:01:41.928741 systemd[1]: Queued start job for default target initrd.target. May 10 10:01:41.928751 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 10 10:01:41.928774 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 10 10:01:41.928786 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 10 10:01:41.928795 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 10 10:01:41.928807 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 10 10:01:41.928816 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 10 10:01:41.928827 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 10 10:01:41.928836 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 10 10:01:41.928845 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 10 10:01:41.928854 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 10 10:01:41.928863 systemd[1]: Reached target paths.target - Path Units. May 10 10:01:41.928874 systemd[1]: Reached target slices.target - Slice Units. May 10 10:01:41.928883 systemd[1]: Reached target swap.target - Swaps. May 10 10:01:41.928892 systemd[1]: Reached target timers.target - Timer Units. May 10 10:01:41.928900 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 10 10:01:41.928909 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 10 10:01:41.928918 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 10 10:01:41.928927 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 10 10:01:41.928936 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 10 10:01:41.928947 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 10 10:01:41.928956 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 10 10:01:41.928964 systemd[1]: Reached target sockets.target - Socket Units. May 10 10:01:41.928973 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 10 10:01:41.928982 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 10 10:01:41.928991 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 10 10:01:41.929000 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 10 10:01:41.929009 systemd[1]: Starting systemd-fsck-usr.service... May 10 10:01:41.929018 systemd[1]: Starting systemd-journald.service - Journal Service... May 10 10:01:41.929030 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 10 10:01:41.929038 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:01:41.929047 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 10 10:01:41.929057 systemd[1]: Finished systemd-fsck-usr.service. May 10 10:01:41.929071 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 10 10:01:41.929106 systemd-journald[193]: Collecting audit messages is disabled. May 10 10:01:41.929130 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 10 10:01:41.929139 systemd-journald[193]: Journal started May 10 10:01:41.929162 systemd-journald[193]: Runtime Journal (/run/log/journal/66ea1ce63deb4f97b980abcc1572d39e) is 6M, max 48.6M, 42.5M free. May 10 10:01:41.921832 systemd-modules-load[196]: Inserted module 'overlay' May 10 10:01:41.962712 systemd[1]: Started systemd-journald.service - Journal Service. May 10 10:01:41.962740 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 10 10:01:41.962752 kernel: Bridge firewalling registered May 10 10:01:41.949091 systemd-modules-load[196]: Inserted module 'br_netfilter' May 10 10:01:41.966404 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 10 10:01:41.968942 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:01:41.971437 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 10 10:01:41.979312 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 10 10:01:41.983271 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 10 10:01:41.993500 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 10 10:01:41.994351 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 10 10:01:42.004597 systemd-tmpfiles[220]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 10 10:01:42.006493 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 10 10:01:42.012513 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 10 10:01:42.014367 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 10 10:01:42.016429 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 10 10:01:42.019295 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 10 10:01:42.034488 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 10 10:01:42.064565 dracut-cmdline[239]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=cdff7a1e66558670c3a31fd90f395811dccc4cb131ce51930f033b8634f7f080 May 10 10:01:42.070735 systemd-resolved[235]: Positive Trust Anchors: May 10 10:01:42.070749 systemd-resolved[235]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 10 10:01:42.070799 systemd-resolved[235]: 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 10 10:01:42.073762 systemd-resolved[235]: Defaulting to hostname 'linux'. May 10 10:01:42.074952 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 10 10:01:42.081327 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 10 10:01:42.187716 kernel: SCSI subsystem initialized May 10 10:01:42.197714 kernel: Loading iSCSI transport class v2.0-870. May 10 10:01:42.208710 kernel: iscsi: registered transport (tcp) May 10 10:01:42.230029 kernel: iscsi: registered transport (qla4xxx) May 10 10:01:42.230063 kernel: QLogic iSCSI HBA Driver May 10 10:01:42.254742 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 10 10:01:42.282019 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 10 10:01:42.286078 systemd[1]: Reached target network-pre.target - Preparation for Network. May 10 10:01:42.346114 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 10 10:01:42.348228 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 10 10:01:42.414726 kernel: raid6: avx2x4 gen() 29229 MB/s May 10 10:01:42.431701 kernel: raid6: avx2x2 gen() 30609 MB/s May 10 10:01:42.448908 kernel: raid6: avx2x1 gen() 25401 MB/s May 10 10:01:42.448952 kernel: raid6: using algorithm avx2x2 gen() 30609 MB/s May 10 10:01:42.466822 kernel: raid6: .... xor() 18116 MB/s, rmw enabled May 10 10:01:42.466849 kernel: raid6: using avx2x2 recovery algorithm May 10 10:01:42.488707 kernel: xor: automatically using best checksumming function avx May 10 10:01:42.638716 kernel: Btrfs loaded, zoned=no, fsverity=no May 10 10:01:42.648439 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 10 10:01:42.651617 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 10 10:01:42.683666 systemd-udevd[447]: Using default interface naming scheme 'v255'. May 10 10:01:42.689458 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 10 10:01:42.691362 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 10 10:01:42.725466 dracut-pre-trigger[451]: rd.md=0: removing MD RAID activation May 10 10:01:42.758448 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 10 10:01:42.762636 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 10 10:01:42.850383 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 10 10:01:42.855516 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 10 10:01:42.893742 kernel: cryptd: max_cpu_qlen set to 1000 May 10 10:01:42.902650 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 May 10 10:01:42.906701 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues May 10 10:01:42.910695 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) May 10 10:01:42.916700 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:01:42.916728 kernel: libata version 3.00 loaded. May 10 10:01:42.916740 kernel: AVX2 version of gcm_enc/dec engaged. May 10 10:01:42.917864 kernel: AES CTR mode by8 optimization enabled May 10 10:01:42.922781 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 10 10:01:42.923964 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:01:42.925380 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:01:42.930762 kernel: ahci 0000:00:1f.2: version 3.0 May 10 10:01:42.930966 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 May 10 10:01:42.931466 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:01:42.935153 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode May 10 10:01:42.935435 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only May 10 10:01:42.937874 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 10 10:01:42.947288 kernel: scsi host0: ahci May 10 10:01:42.950098 kernel: scsi host1: ahci May 10 10:01:42.952708 kernel: scsi host2: ahci May 10 10:01:42.955763 kernel: scsi host3: ahci May 10 10:01:42.957975 kernel: BTRFS: device fsid 447a9416-2d70-470c-8858-df3b82fa5271 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (508) May 10 10:01:42.957999 kernel: scsi host4: ahci May 10 10:01:42.958697 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (493) May 10 10:01:42.966555 kernel: scsi host5: ahci May 10 10:01:42.966838 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 May 10 10:01:42.966851 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 May 10 10:01:42.966862 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 May 10 10:01:42.966872 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 May 10 10:01:42.966883 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 May 10 10:01:42.966893 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 May 10 10:01:42.984496 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 10 10:01:42.999023 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 10 10:01:43.027459 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 10 10:01:43.027558 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 10 10:01:43.031341 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:01:43.042405 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 10 10:01:43.043458 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 10 10:01:43.068727 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:01:43.074712 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:01:43.280718 kernel: ata1: SATA link down (SStatus 0 SControl 300) May 10 10:01:43.280798 kernel: ata4: SATA link down (SStatus 0 SControl 300) May 10 10:01:43.281725 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 10 10:01:43.282952 kernel: ata2: SATA link down (SStatus 0 SControl 300) May 10 10:01:43.282989 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 May 10 10:01:43.284996 kernel: ata3.00: applying bridge limits May 10 10:01:43.285011 kernel: ata3.00: configured for UDMA/100 May 10 10:01:43.285706 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 May 10 10:01:43.289714 kernel: ata6: SATA link down (SStatus 0 SControl 300) May 10 10:01:43.289732 kernel: ata5: SATA link down (SStatus 0 SControl 300) May 10 10:01:43.336122 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray May 10 10:01:43.336497 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 10 10:01:43.354730 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 May 10 10:01:43.663062 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 10 10:01:43.665971 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 10 10:01:43.672237 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 10 10:01:43.674545 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 10 10:01:43.677648 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 10 10:01:43.699015 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 10 10:01:44.137512 disk-uuid[597]: The operation has completed successfully. May 10 10:01:44.139271 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:01:44.175133 systemd[1]: disk-uuid.service: Deactivated successfully. May 10 10:01:44.175261 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 10 10:01:44.208793 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 10 10:01:44.227638 sh[627]: Success May 10 10:01:44.247731 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 10 10:01:44.247811 kernel: device-mapper: uevent: version 1.0.3 May 10 10:01:44.247824 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 10 10:01:44.258713 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" May 10 10:01:44.291072 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 10 10:01:44.293470 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 10 10:01:44.315907 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 10 10:01:44.322124 kernel: BTRFS info (device dm-0): first mount of filesystem 447a9416-2d70-470c-8858-df3b82fa5271 May 10 10:01:44.322156 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 10 10:01:44.322176 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 10 10:01:44.323981 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 10 10:01:44.323999 kernel: BTRFS info (device dm-0): using free space tree May 10 10:01:44.328921 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 10 10:01:44.329459 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 10 10:01:44.332085 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 10 10:01:44.333007 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 10 10:01:44.335298 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 10 10:01:44.360833 kernel: BTRFS info (device vda6): first mount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:01:44.360880 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 10 10:01:44.360892 kernel: BTRFS info (device vda6): using free space tree May 10 10:01:44.364695 kernel: BTRFS info (device vda6): auto enabling async discard May 10 10:01:44.368696 kernel: BTRFS info (device vda6): last unmount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:01:44.452979 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 10 10:01:44.458654 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 10 10:01:44.471938 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 10 10:01:44.476177 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 10 10:01:44.505313 systemd-networkd[806]: lo: Link UP May 10 10:01:44.505323 systemd-networkd[806]: lo: Gained carrier May 10 10:01:44.507106 systemd-networkd[806]: Enumeration completed May 10 10:01:44.507239 systemd[1]: Started systemd-networkd.service - Network Configuration. May 10 10:01:44.507479 systemd-networkd[806]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:01:44.507483 systemd-networkd[806]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 10 10:01:44.507563 systemd[1]: Reached target network.target - Network. May 10 10:01:44.508532 systemd-networkd[806]: eth0: Link UP May 10 10:01:44.508536 systemd-networkd[806]: eth0: Gained carrier May 10 10:01:44.508545 systemd-networkd[806]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:01:44.530780 systemd-networkd[806]: eth0: DHCPv4 address 10.0.0.110/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 10 10:01:44.571984 ignition[809]: Ignition 2.21.0 May 10 10:01:44.571997 ignition[809]: Stage: fetch-offline May 10 10:01:44.572032 ignition[809]: no configs at "/usr/lib/ignition/base.d" May 10 10:01:44.572042 ignition[809]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:01:44.572127 ignition[809]: parsed url from cmdline: "" May 10 10:01:44.572131 ignition[809]: no config URL provided May 10 10:01:44.572136 ignition[809]: reading system config file "/usr/lib/ignition/user.ign" May 10 10:01:44.572146 ignition[809]: no config at "/usr/lib/ignition/user.ign" May 10 10:01:44.572170 ignition[809]: op(1): [started] loading QEMU firmware config module May 10 10:01:44.572175 ignition[809]: op(1): executing: "modprobe" "qemu_fw_cfg" May 10 10:01:44.583476 ignition[809]: op(1): [finished] loading QEMU firmware config module May 10 10:01:44.585116 ignition[809]: parsing config with SHA512: 389be19007096a4f9ef4390629243ac5a7e9d6e0b8e9901fa56b42d69a4532c7fef9da17d854116c8c058ae82012da3737c9ea61986d7f1c5068de7217aa8f0b May 10 10:01:44.592258 unknown[809]: fetched base config from "system" May 10 10:01:44.592278 unknown[809]: fetched user config from "qemu" May 10 10:01:44.593284 systemd-resolved[235]: Detected conflict on linux IN A 10.0.0.110 May 10 10:01:44.593584 ignition[809]: fetch-offline: fetch-offline passed May 10 10:01:44.593293 systemd-resolved[235]: Hostname conflict, changing published hostname from 'linux' to 'linux9'. May 10 10:01:44.594570 ignition[809]: Ignition finished successfully May 10 10:01:44.602523 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 10 10:01:44.605436 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 10 10:01:44.606310 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 10 10:01:44.642561 ignition[822]: Ignition 2.21.0 May 10 10:01:44.642576 ignition[822]: Stage: kargs May 10 10:01:44.642894 ignition[822]: no configs at "/usr/lib/ignition/base.d" May 10 10:01:44.642909 ignition[822]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:01:44.648230 ignition[822]: kargs: kargs passed May 10 10:01:44.648318 ignition[822]: Ignition finished successfully May 10 10:01:44.652829 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 10 10:01:44.656761 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 10 10:01:44.702704 ignition[830]: Ignition 2.21.0 May 10 10:01:44.702721 ignition[830]: Stage: disks May 10 10:01:44.702976 ignition[830]: no configs at "/usr/lib/ignition/base.d" May 10 10:01:44.702994 ignition[830]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:01:44.703752 ignition[830]: disks: disks passed May 10 10:01:44.703810 ignition[830]: Ignition finished successfully May 10 10:01:44.711033 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 10 10:01:44.713371 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 10 10:01:44.713463 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 10 10:01:44.715628 systemd[1]: Reached target local-fs.target - Local File Systems. May 10 10:01:44.718924 systemd[1]: Reached target sysinit.target - System Initialization. May 10 10:01:44.719163 systemd[1]: Reached target basic.target - Basic System. May 10 10:01:44.720823 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 10 10:01:44.756061 systemd-fsck[840]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 10 10:01:44.762844 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 10 10:01:44.766564 systemd[1]: Mounting sysroot.mount - /sysroot... May 10 10:01:44.891718 kernel: EXT4-fs (vda9): mounted filesystem f8cce592-76ea-4219-9560-1ef21b28761f r/w with ordered data mode. Quota mode: none. May 10 10:01:44.893244 systemd[1]: Mounted sysroot.mount - /sysroot. May 10 10:01:44.894122 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 10 10:01:44.896775 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 10 10:01:44.899875 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 10 10:01:44.901311 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 10 10:01:44.901368 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 10 10:01:44.901400 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 10 10:01:44.914134 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 10 10:01:44.917999 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (848) May 10 10:01:44.915913 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 10 10:01:44.921807 kernel: BTRFS info (device vda6): first mount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:01:44.921824 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 10 10:01:44.921835 kernel: BTRFS info (device vda6): using free space tree May 10 10:01:44.923702 kernel: BTRFS info (device vda6): auto enabling async discard May 10 10:01:44.939198 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 10 10:01:44.976788 initrd-setup-root[872]: cut: /sysroot/etc/passwd: No such file or directory May 10 10:01:44.981547 initrd-setup-root[879]: cut: /sysroot/etc/group: No such file or directory May 10 10:01:44.987424 initrd-setup-root[886]: cut: /sysroot/etc/shadow: No such file or directory May 10 10:01:44.992540 initrd-setup-root[893]: cut: /sysroot/etc/gshadow: No such file or directory May 10 10:01:45.087016 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 10 10:01:45.089318 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 10 10:01:45.091036 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 10 10:01:45.112716 kernel: BTRFS info (device vda6): last unmount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:01:45.139169 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 10 10:01:45.157873 ignition[962]: INFO : Ignition 2.21.0 May 10 10:01:45.157873 ignition[962]: INFO : Stage: mount May 10 10:01:45.159708 ignition[962]: INFO : no configs at "/usr/lib/ignition/base.d" May 10 10:01:45.159708 ignition[962]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:01:45.163070 ignition[962]: INFO : mount: mount passed May 10 10:01:45.164000 ignition[962]: INFO : Ignition finished successfully May 10 10:01:45.167956 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 10 10:01:45.170574 systemd[1]: Starting ignition-files.service - Ignition (files)... May 10 10:01:45.321745 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 10 10:01:45.323739 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 10 10:01:45.346724 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (975) May 10 10:01:45.349371 kernel: BTRFS info (device vda6): first mount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:01:45.349390 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 10 10:01:45.349401 kernel: BTRFS info (device vda6): using free space tree May 10 10:01:45.353705 kernel: BTRFS info (device vda6): auto enabling async discard May 10 10:01:45.354936 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 10 10:01:45.389114 ignition[992]: INFO : Ignition 2.21.0 May 10 10:01:45.389114 ignition[992]: INFO : Stage: files May 10 10:01:45.391305 ignition[992]: INFO : no configs at "/usr/lib/ignition/base.d" May 10 10:01:45.391305 ignition[992]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:01:45.391305 ignition[992]: DEBUG : files: compiled without relabeling support, skipping May 10 10:01:45.395808 ignition[992]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 10 10:01:45.395808 ignition[992]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 10 10:01:45.395808 ignition[992]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 10 10:01:45.395808 ignition[992]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 10 10:01:45.395808 ignition[992]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 10 10:01:45.395496 unknown[992]: wrote ssh authorized keys file for user: core May 10 10:01:45.405285 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 10 10:01:45.405285 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 10 10:01:45.405285 ignition[992]: INFO : files: op(4): [started] processing unit "etcd-member.service" May 10 10:01:45.405285 ignition[992]: 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 10 10:01:45.405285 ignition[992]: 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 10 10:01:45.405285 ignition[992]: INFO : files: op(4): [finished] processing unit "etcd-member.service" May 10 10:01:45.405285 ignition[992]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" May 10 10:01:45.405285 ignition[992]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 10 10:01:45.405285 ignition[992]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 10 10:01:45.405285 ignition[992]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" May 10 10:01:45.405285 ignition[992]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" May 10 10:01:45.427930 ignition[992]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" May 10 10:01:45.427930 ignition[992]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 10 10:01:45.427930 ignition[992]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" May 10 10:01:45.427930 ignition[992]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" May 10 10:01:45.427930 ignition[992]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" May 10 10:01:45.427930 ignition[992]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 10 10:01:45.427930 ignition[992]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 10 10:01:45.427930 ignition[992]: INFO : files: files passed May 10 10:01:45.427930 ignition[992]: INFO : Ignition finished successfully May 10 10:01:45.425627 systemd[1]: Finished ignition-files.service - Ignition (files). May 10 10:01:45.429053 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 10 10:01:45.431820 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 10 10:01:45.449063 systemd[1]: ignition-quench.service: Deactivated successfully. May 10 10:01:45.449206 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 10 10:01:45.452376 initrd-setup-root-after-ignition[1020]: grep: /sysroot/oem/oem-release: No such file or directory May 10 10:01:45.453843 initrd-setup-root-after-ignition[1023]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 10 10:01:45.453843 initrd-setup-root-after-ignition[1023]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 10 10:01:45.457206 initrd-setup-root-after-ignition[1027]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 10 10:01:45.457581 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 10 10:01:45.460319 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 10 10:01:45.463374 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 10 10:01:45.533307 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 10 10:01:45.533463 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 10 10:01:45.535968 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 10 10:01:45.538030 systemd[1]: Reached target initrd.target - Initrd Default Target. May 10 10:01:45.540145 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 10 10:01:45.541207 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 10 10:01:45.574056 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 10 10:01:45.578294 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 10 10:01:45.603269 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 10 10:01:45.604876 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 10 10:01:45.607500 systemd[1]: Stopped target timers.target - Timer Units. May 10 10:01:45.609903 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 10 10:01:45.610050 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 10 10:01:45.612900 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 10 10:01:45.614723 systemd[1]: Stopped target basic.target - Basic System. May 10 10:01:45.617096 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 10 10:01:45.619616 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 10 10:01:45.622098 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 10 10:01:45.624609 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 10 10:01:45.627426 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 10 10:01:45.630046 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 10 10:01:45.632811 systemd[1]: Stopped target sysinit.target - System Initialization. May 10 10:01:45.635221 systemd[1]: Stopped target local-fs.target - Local File Systems. May 10 10:01:45.637843 systemd[1]: Stopped target swap.target - Swaps. May 10 10:01:45.639986 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 10 10:01:45.640172 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 10 10:01:45.642786 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 10 10:01:45.644750 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 10 10:01:45.647210 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 10 10:01:45.647326 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 10 10:01:45.649734 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 10 10:01:45.649880 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 10 10:01:45.652231 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 10 10:01:45.652370 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 10 10:01:45.654221 systemd[1]: Stopped target paths.target - Path Units. May 10 10:01:45.655990 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 10 10:01:45.657759 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 10 10:01:45.688722 systemd[1]: Stopped target slices.target - Slice Units. May 10 10:01:45.690635 systemd[1]: Stopped target sockets.target - Socket Units. May 10 10:01:45.692594 systemd[1]: iscsid.socket: Deactivated successfully. May 10 10:01:45.692740 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 10 10:01:45.711351 systemd[1]: iscsiuio.socket: Deactivated successfully. May 10 10:01:45.711504 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 10 10:01:45.713618 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 10 10:01:45.713855 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 10 10:01:45.716179 systemd[1]: ignition-files.service: Deactivated successfully. May 10 10:01:45.716327 systemd[1]: Stopped ignition-files.service - Ignition (files). May 10 10:01:45.719704 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 10 10:01:45.721162 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 10 10:01:45.721328 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 10 10:01:45.724275 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 10 10:01:45.726371 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 10 10:01:45.726550 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 10 10:01:45.729051 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 10 10:01:45.748160 ignition[1048]: INFO : Ignition 2.21.0 May 10 10:01:45.748160 ignition[1048]: INFO : Stage: umount May 10 10:01:45.748160 ignition[1048]: INFO : no configs at "/usr/lib/ignition/base.d" May 10 10:01:45.748160 ignition[1048]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:01:45.748160 ignition[1048]: INFO : umount: umount passed May 10 10:01:45.748160 ignition[1048]: INFO : Ignition finished successfully May 10 10:01:45.729258 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 10 10:01:45.737509 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 10 10:01:45.737656 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 10 10:01:45.751094 systemd[1]: ignition-mount.service: Deactivated successfully. May 10 10:01:45.751268 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 10 10:01:45.752817 systemd[1]: Stopped target network.target - Network. May 10 10:01:45.754867 systemd[1]: ignition-disks.service: Deactivated successfully. May 10 10:01:45.754942 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 10 10:01:45.757263 systemd[1]: ignition-kargs.service: Deactivated successfully. May 10 10:01:45.757334 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 10 10:01:45.759229 systemd[1]: ignition-setup.service: Deactivated successfully. May 10 10:01:45.759295 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 10 10:01:45.761327 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 10 10:01:45.761389 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 10 10:01:45.763574 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 10 10:01:45.765825 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 10 10:01:45.769118 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 10 10:01:45.769894 systemd[1]: sysroot-boot.service: Deactivated successfully. May 10 10:01:45.770024 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 10 10:01:45.772658 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 10 10:01:45.772795 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 10 10:01:45.775207 systemd[1]: systemd-resolved.service: Deactivated successfully. May 10 10:01:45.775377 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 10 10:01:45.781249 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 10 10:01:45.781622 systemd[1]: systemd-networkd.service: Deactivated successfully. May 10 10:01:45.781796 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 10 10:01:45.785418 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 10 10:01:45.786368 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 10 10:01:45.788394 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 10 10:01:45.788453 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 10 10:01:45.791427 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 10 10:01:45.793296 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 10 10:01:45.793359 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 10 10:01:45.795537 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 10 10:01:45.795604 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 10 10:01:45.797715 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 10 10:01:45.797780 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 10 10:01:45.799914 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 10 10:01:45.799977 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 10 10:01:45.802168 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 10 10:01:45.828832 systemd[1]: systemd-udevd.service: Deactivated successfully. May 10 10:01:45.829064 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 10 10:01:45.831710 systemd[1]: network-cleanup.service: Deactivated successfully. May 10 10:01:45.831874 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 10 10:01:45.834288 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 10 10:01:45.834368 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 10 10:01:45.835548 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 10 10:01:45.835603 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 10 10:01:45.837506 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 10 10:01:45.837576 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 10 10:01:45.839901 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 10 10:01:45.839965 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 10 10:01:45.841662 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 10 10:01:45.841772 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 10 10:01:45.844785 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 10 10:01:45.845997 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 10 10:01:45.846105 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 10 10:01:45.849258 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 10 10:01:45.849337 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 10 10:01:45.851581 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 10 10:01:45.851651 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:01:45.863608 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 10 10:01:45.863775 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 10 10:01:45.865818 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 10 10:01:45.868640 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 10 10:01:45.893031 systemd[1]: Switching root. May 10 10:01:45.930670 systemd-journald[193]: Journal stopped May 10 10:01:47.086982 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). May 10 10:01:47.087049 kernel: SELinux: policy capability network_peer_controls=1 May 10 10:01:47.087067 kernel: SELinux: policy capability open_perms=1 May 10 10:01:47.087079 kernel: SELinux: policy capability extended_socket_class=1 May 10 10:01:47.087092 kernel: SELinux: policy capability always_check_network=0 May 10 10:01:47.087103 kernel: SELinux: policy capability cgroup_seclabel=1 May 10 10:01:47.087121 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 10 10:01:47.087137 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 10 10:01:47.087149 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 10 10:01:47.087160 kernel: audit: type=1403 audit(1746871306.135:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 10 10:01:47.087173 systemd[1]: Successfully loaded SELinux policy in 89.886ms. May 10 10:01:47.087196 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 18.800ms. May 10 10:01:47.087211 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 10 10:01:47.087225 systemd[1]: Detected virtualization kvm. May 10 10:01:47.087237 systemd[1]: Detected architecture x86-64. May 10 10:01:47.087249 systemd[1]: Detected first boot. May 10 10:01:47.087265 systemd[1]: Initializing machine ID from VM UUID. May 10 10:01:47.087280 zram_generator::config[1094]: No configuration found. May 10 10:01:47.087293 kernel: Guest personality initialized and is inactive May 10 10:01:47.087305 kernel: VMCI host device registered (name=vmci, major=10, minor=125) May 10 10:01:47.087316 kernel: Initialized host personality May 10 10:01:47.087331 kernel: NET: Registered PF_VSOCK protocol family May 10 10:01:47.087349 systemd[1]: Populated /etc with preset unit settings. May 10 10:01:47.087362 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 10 10:01:47.087375 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 10 10:01:47.087387 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 10 10:01:47.087401 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 10 10:01:47.087414 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 10 10:01:47.087427 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 10 10:01:47.087449 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 10 10:01:47.087462 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 10 10:01:47.087475 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 10 10:01:47.087487 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 10 10:01:47.087500 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 10 10:01:47.087512 systemd[1]: Created slice user.slice - User and Session Slice. May 10 10:01:47.087524 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 10 10:01:47.087537 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 10 10:01:47.087549 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 10 10:01:47.087565 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 10 10:01:47.087584 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 10 10:01:47.087596 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 10 10:01:47.087609 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 10 10:01:47.087621 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 10 10:01:47.087634 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 10 10:01:47.087646 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 10 10:01:47.087658 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 10 10:01:47.087686 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 10 10:01:47.087701 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 10 10:01:47.087713 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 10 10:01:47.087726 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 10 10:01:47.087738 systemd[1]: Reached target slices.target - Slice Units. May 10 10:01:47.087750 systemd[1]: Reached target swap.target - Swaps. May 10 10:01:47.087763 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 10 10:01:47.087775 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 10 10:01:47.087788 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 10 10:01:47.087804 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 10 10:01:47.087816 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 10 10:01:47.087828 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 10 10:01:47.087840 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 10 10:01:47.087853 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 10 10:01:47.087866 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 10 10:01:47.087878 systemd[1]: Mounting media.mount - External Media Directory... May 10 10:01:47.087891 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:01:47.087903 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 10 10:01:47.087919 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 10 10:01:47.087931 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 10 10:01:47.087944 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 10 10:01:47.087956 systemd[1]: Reached target machines.target - Containers. May 10 10:01:47.087970 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 10 10:01:47.087983 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 10 10:01:47.087995 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 10 10:01:47.088012 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 10 10:01:47.088027 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 10 10:01:47.088040 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 10 10:01:47.088052 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 10 10:01:47.088065 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 10 10:01:47.088077 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 10 10:01:47.088090 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 10 10:01:47.088103 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 10 10:01:47.088115 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 10 10:01:47.088128 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 10 10:01:47.088143 systemd[1]: Stopped systemd-fsck-usr.service. May 10 10:01:47.088157 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 10 10:01:47.088169 systemd[1]: Starting systemd-journald.service - Journal Service... May 10 10:01:47.088182 kernel: loop: module loaded May 10 10:01:47.088194 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 10 10:01:47.088206 kernel: fuse: init (API version 7.39) May 10 10:01:47.088218 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 10 10:01:47.088231 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 10 10:01:47.088248 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 10 10:01:47.088260 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 10 10:01:47.088273 systemd[1]: verity-setup.service: Deactivated successfully. May 10 10:01:47.088285 systemd[1]: Stopped verity-setup.service. May 10 10:01:47.088298 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:01:47.088313 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 10 10:01:47.088325 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 10 10:01:47.088337 systemd[1]: Mounted media.mount - External Media Directory. May 10 10:01:47.088370 systemd-journald[1158]: Collecting audit messages is disabled. May 10 10:01:47.088393 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 10 10:01:47.088405 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 10 10:01:47.088418 systemd-journald[1158]: Journal started May 10 10:01:47.088450 systemd-journald[1158]: Runtime Journal (/run/log/journal/66ea1ce63deb4f97b980abcc1572d39e) is 6M, max 48.6M, 42.5M free. May 10 10:01:46.783430 systemd[1]: Queued start job for default target multi-user.target. May 10 10:01:46.798326 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 10 10:01:46.798910 systemd[1]: systemd-journald.service: Deactivated successfully. May 10 10:01:47.094937 systemd[1]: Started systemd-journald.service - Journal Service. May 10 10:01:47.095030 kernel: ACPI: bus type drm_connector registered May 10 10:01:47.094690 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 10 10:01:47.096139 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 10 10:01:47.097937 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 10 10:01:47.098204 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 10 10:01:47.100000 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 10 10:01:47.100254 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 10 10:01:47.102205 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 10 10:01:47.104157 systemd[1]: modprobe@drm.service: Deactivated successfully. May 10 10:01:47.104420 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 10 10:01:47.106454 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 10 10:01:47.106741 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 10 10:01:47.108833 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 10 10:01:47.109108 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 10 10:01:47.110859 systemd[1]: modprobe@loop.service: Deactivated successfully. May 10 10:01:47.111125 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 10 10:01:47.113019 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 10 10:01:47.114946 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 10 10:01:47.116974 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 10 10:01:47.119044 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 10 10:01:47.138457 systemd[1]: Reached target network-pre.target - Preparation for Network. May 10 10:01:47.141971 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 10 10:01:47.144649 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 10 10:01:47.145886 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 10 10:01:47.145913 systemd[1]: Reached target local-fs.target - Local File Systems. May 10 10:01:47.148329 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 10 10:01:47.156474 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 10 10:01:47.157994 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 10 10:01:47.159716 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 10 10:01:47.162625 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 10 10:01:47.164806 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 10 10:01:47.168834 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 10 10:01:47.170283 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 10 10:01:47.175861 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 10 10:01:47.180817 systemd-journald[1158]: Time spent on flushing to /var/log/journal/66ea1ce63deb4f97b980abcc1572d39e is 28.023ms for 940 entries. May 10 10:01:47.180817 systemd-journald[1158]: System Journal (/var/log/journal/66ea1ce63deb4f97b980abcc1572d39e) is 8M, max 195.6M, 187.6M free. May 10 10:01:47.226162 systemd-journald[1158]: Received client request to flush runtime journal. May 10 10:01:47.226234 kernel: loop0: detected capacity change from 0 to 113872 May 10 10:01:47.179859 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 10 10:01:47.185404 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 10 10:01:47.194047 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 10 10:01:47.195673 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 10 10:01:47.197107 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 10 10:01:47.210294 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 10 10:01:47.212022 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 10 10:01:47.217027 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 10 10:01:47.230751 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 10 10:01:47.233109 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 10 10:01:47.244734 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 10 10:01:47.249659 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 10 10:01:47.254336 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 10 10:01:47.263103 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 10 10:01:47.276215 kernel: loop1: detected capacity change from 0 to 146240 May 10 10:01:47.288603 systemd-tmpfiles[1229]: ACLs are not supported, ignoring. May 10 10:01:47.288625 systemd-tmpfiles[1229]: ACLs are not supported, ignoring. May 10 10:01:47.298146 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 10 10:01:47.314726 kernel: loop2: detected capacity change from 0 to 113872 May 10 10:01:47.327714 kernel: loop3: detected capacity change from 0 to 146240 May 10 10:01:47.344171 (sd-merge)[1234]: Using extensions 'containerd-flatcar', 'docker-flatcar'. May 10 10:01:47.344813 (sd-merge)[1234]: Merged extensions into '/usr'. May 10 10:01:47.348784 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 10 10:01:47.352705 systemd[1]: Starting ensure-sysext.service... May 10 10:01:47.356138 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 10 10:01:47.370854 systemd[1]: Reload requested from client PID 1236 ('systemctl') (unit ensure-sysext.service)... May 10 10:01:47.371021 systemd[1]: Reloading... May 10 10:01:47.383293 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 10 10:01:47.383787 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 10 10:01:47.384269 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 10 10:01:47.384726 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 10 10:01:47.385982 systemd-tmpfiles[1237]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 10 10:01:47.386324 systemd-tmpfiles[1237]: ACLs are not supported, ignoring. May 10 10:01:47.386412 systemd-tmpfiles[1237]: ACLs are not supported, ignoring. May 10 10:01:47.394140 systemd-tmpfiles[1237]: Detected autofs mount point /boot during canonicalization of boot. May 10 10:01:47.394158 systemd-tmpfiles[1237]: Skipping /boot May 10 10:01:47.412474 systemd-tmpfiles[1237]: Detected autofs mount point /boot during canonicalization of boot. May 10 10:01:47.412655 systemd-tmpfiles[1237]: Skipping /boot May 10 10:01:47.461710 zram_generator::config[1265]: No configuration found. May 10 10:01:47.571771 ldconfig[1208]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 10 10:01:47.581059 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 10 10:01:47.661946 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 10 10:01:47.662614 systemd[1]: Reloading finished in 291 ms. May 10 10:01:47.686868 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 10 10:01:47.706253 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 10 10:01:47.716413 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 10 10:01:47.719120 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 10 10:01:47.734661 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 10 10:01:47.738715 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 10 10:01:47.749805 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 10 10:01:47.753406 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 10 10:01:47.755387 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 10 10:01:47.757859 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 10 10:01:47.760944 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 10 10:01:47.762479 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 10 10:01:47.762588 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 10 10:01:47.764136 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 10 10:01:47.764376 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 10 10:01:47.766650 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 10 10:01:47.766906 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 10 10:01:47.773340 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 10 10:01:47.776280 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 10 10:01:47.779188 systemd[1]: modprobe@loop.service: Deactivated successfully. May 10 10:01:47.780509 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 10 10:01:47.793343 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 10 10:01:47.796662 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 10 10:01:47.800341 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 10 10:01:47.803891 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 10 10:01:47.810925 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 10 10:01:47.816373 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 10 10:01:47.817877 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 10 10:01:47.818040 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 10 10:01:47.823769 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 10 10:01:47.826439 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 10 10:01:47.828712 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 10 10:01:47.829059 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 10 10:01:47.831427 systemd[1]: modprobe@drm.service: Deactivated successfully. May 10 10:01:47.832250 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 10 10:01:47.834301 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 10 10:01:47.834615 augenrules[1340]: No rules May 10 10:01:47.835039 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 10 10:01:47.837379 systemd[1]: audit-rules.service: Deactivated successfully. May 10 10:01:47.837971 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 10 10:01:47.847509 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 10 10:01:47.849548 systemd[1]: Finished ensure-sysext.service. May 10 10:01:47.851048 systemd[1]: modprobe@loop.service: Deactivated successfully. May 10 10:01:47.851459 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 10 10:01:47.865333 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:01:47.865383 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 10 10:01:47.865458 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 10 10:01:47.867802 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 10 10:01:47.870834 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 10 10:01:47.875834 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 10 10:01:47.877083 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:01:47.877887 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 10 10:01:47.881056 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 10 10:01:47.906484 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 10 10:01:47.926270 systemd-udevd[1359]: Using default interface naming scheme 'v255'. May 10 10:01:47.940484 systemd-resolved[1307]: Positive Trust Anchors: May 10 10:01:47.940503 systemd-resolved[1307]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 10 10:01:47.940549 systemd-resolved[1307]: 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 10 10:01:47.946071 systemd-resolved[1307]: Defaulting to hostname 'linux'. May 10 10:01:47.948346 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 10 10:01:47.949746 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 10 10:01:47.953255 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 10 10:01:47.954794 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 10 10:01:47.957036 systemd[1]: Reached target sysinit.target - System Initialization. May 10 10:01:47.958488 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 10 10:01:47.959947 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 10 10:01:47.961542 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. May 10 10:01:47.962937 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 10 10:01:47.965629 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 10 10:01:47.965661 systemd[1]: Reached target paths.target - Path Units. May 10 10:01:47.966824 systemd[1]: Reached target time-set.target - System Time Set. May 10 10:01:47.968293 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 10 10:01:47.969778 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 10 10:01:47.971708 systemd[1]: Reached target timers.target - Timer Units. May 10 10:01:47.974055 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 10 10:01:47.977487 systemd[1]: Starting docker.socket - Docker Socket for the API... May 10 10:01:47.982487 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 10 10:01:47.985961 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 10 10:01:47.987692 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 10 10:01:47.995864 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 10 10:01:47.999238 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 10 10:01:48.005973 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 10 10:01:48.009039 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 10 10:01:48.020213 systemd[1]: Reached target sockets.target - Socket Units. May 10 10:01:48.023862 systemd[1]: Reached target basic.target - Basic System. May 10 10:01:48.025177 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 10 10:01:48.025209 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 10 10:01:48.030472 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 10 10:01:48.032868 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 10 10:01:48.035139 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 10 10:01:48.048129 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 10 10:01:48.052945 jq[1397]: false May 10 10:01:48.049392 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 10 10:01:48.053326 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... May 10 10:01:48.061125 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 10 10:01:48.065917 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 10 10:01:48.069478 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 10 10:01:48.074317 google_oslogin_nss_cache[1401]: oslogin_cache_refresh[1401]: Refreshing passwd entry cache May 10 10:01:48.069803 oslogin_cache_refresh[1401]: Refreshing passwd entry cache May 10 10:01:48.079026 google_oslogin_nss_cache[1401]: oslogin_cache_refresh[1401]: Failure getting users, quitting May 10 10:01:48.079026 google_oslogin_nss_cache[1401]: oslogin_cache_refresh[1401]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 10 10:01:48.079026 google_oslogin_nss_cache[1401]: oslogin_cache_refresh[1401]: Refreshing group entry cache May 10 10:01:48.077575 oslogin_cache_refresh[1401]: Failure getting users, quitting May 10 10:01:48.077594 oslogin_cache_refresh[1401]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 10 10:01:48.077645 oslogin_cache_refresh[1401]: Refreshing group entry cache May 10 10:01:48.081718 google_oslogin_nss_cache[1401]: oslogin_cache_refresh[1401]: Failure getting groups, quitting May 10 10:01:48.081718 google_oslogin_nss_cache[1401]: oslogin_cache_refresh[1401]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 10 10:01:48.079376 oslogin_cache_refresh[1401]: Failure getting groups, quitting May 10 10:01:48.079387 oslogin_cache_refresh[1401]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 10 10:01:48.085004 systemd[1]: Starting systemd-logind.service - User Login Management... May 10 10:01:48.088066 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 10 10:01:48.088645 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 10 10:01:48.093713 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1369) May 10 10:01:48.093925 systemd[1]: Starting update-engine.service - Update Engine... May 10 10:01:48.100313 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 10 10:01:48.116197 jq[1413]: true May 10 10:01:48.126073 update_engine[1412]: I20250510 10:01:48.125666 1412 main.cc:92] Flatcar Update Engine starting May 10 10:01:48.125760 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 10 10:01:48.127437 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 10 10:01:48.128042 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 10 10:01:48.128381 systemd[1]: google-oslogin-cache.service: Deactivated successfully. May 10 10:01:48.128628 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. May 10 10:01:48.131068 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 10 10:01:48.131305 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 10 10:01:48.134770 extend-filesystems[1398]: Found loop2 May 10 10:01:48.136577 extend-filesystems[1398]: Found loop3 May 10 10:01:48.136577 extend-filesystems[1398]: Found sr0 May 10 10:01:48.136577 extend-filesystems[1398]: Found vda May 10 10:01:48.136577 extend-filesystems[1398]: Found vda1 May 10 10:01:48.136577 extend-filesystems[1398]: Found vda2 May 10 10:01:48.136577 extend-filesystems[1398]: Found vda3 May 10 10:01:48.136577 extend-filesystems[1398]: Found usr May 10 10:01:48.136577 extend-filesystems[1398]: Found vda4 May 10 10:01:48.136577 extend-filesystems[1398]: Found vda6 May 10 10:01:48.136577 extend-filesystems[1398]: Found vda7 May 10 10:01:48.136577 extend-filesystems[1398]: Found vda9 May 10 10:01:48.136577 extend-filesystems[1398]: Checking size of /dev/vda9 May 10 10:01:48.151011 systemd[1]: motdgen.service: Deactivated successfully. May 10 10:01:48.151332 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 10 10:01:48.154199 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 10 10:01:48.156075 jq[1424]: true May 10 10:01:48.159941 extend-filesystems[1398]: Old size kept for /dev/vda9 May 10 10:01:48.167348 systemd[1]: extend-filesystems.service: Deactivated successfully. May 10 10:01:48.167784 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 10 10:01:48.351558 update_engine[1412]: I20250510 10:01:48.189527 1412 update_check_scheduler.cc:74] Next update check in 5m59s May 10 10:01:48.351763 sshd_keygen[1420]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 10 10:01:48.186002 dbus-daemon[1395]: [system] SELinux support is enabled May 10 10:01:48.359001 kernel: mousedev: PS/2 mouse device common for all mice May 10 10:01:48.361097 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 10 10:01:48.381574 systemd[1]: Started update-engine.service - Update Engine. May 10 10:01:48.386697 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 10 10:01:48.388230 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 10 10:01:48.388263 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 10 10:01:48.396955 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 10 10:01:48.398382 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 10 10:01:48.398426 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 10 10:01:48.401934 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 10 10:01:48.404208 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 10 10:01:48.414755 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 May 10 10:01:48.415908 systemd[1]: Starting issuegen.service - Generate /run/issue... May 10 10:01:48.420793 kernel: ACPI: button: Power Button [PWRF] May 10 10:01:48.436750 bash[1461]: Updated "/home/core/.ssh/authorized_keys" May 10 10:01:48.446500 systemd-networkd[1394]: lo: Link UP May 10 10:01:48.446516 systemd-networkd[1394]: lo: Gained carrier May 10 10:01:48.450341 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 10 10:01:48.455591 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 10 10:01:48.457255 systemd-networkd[1394]: Enumeration completed May 10 10:01:48.457411 systemd[1]: Started systemd-networkd.service - Network Configuration. May 10 10:01:48.458720 systemd[1]: Reached target network.target - Network. May 10 10:01:48.458907 systemd-networkd[1394]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:01:48.458913 systemd-networkd[1394]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 10 10:01:48.463366 systemd-networkd[1394]: eth0: Link UP May 10 10:01:48.463383 systemd-networkd[1394]: eth0: Gained carrier May 10 10:01:48.463418 systemd-networkd[1394]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:01:48.464269 systemd[1]: Starting containerd.service - containerd container runtime... May 10 10:01:48.469044 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 10 10:01:48.477910 systemd-logind[1411]: New seat seat0. May 10 10:01:48.477934 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 10 10:01:48.478836 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 10 10:01:48.479296 systemd[1]: Started systemd-logind.service - User Login Management. May 10 10:01:48.497259 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 10 10:01:48.499753 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) May 10 10:01:48.500067 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD May 10 10:01:48.503813 systemd-networkd[1394]: eth0: DHCPv4 address 10.0.0.110/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 10 10:01:48.506087 systemd-timesyncd[1358]: Network configuration changed, trying to establish connection. May 10 10:01:48.658445 systemd-timesyncd[1358]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 10 10:01:48.658516 systemd-timesyncd[1358]: Initial clock synchronization to Sat 2025-05-10 10:01:48.849143 UTC. May 10 10:01:48.713581 systemd[1]: issuegen.service: Deactivated successfully. May 10 10:01:48.713981 systemd[1]: Finished issuegen.service - Generate /run/issue. May 10 10:01:48.772321 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 10 10:01:48.772819 locksmithd[1460]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 10 10:01:48.777353 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:01:48.794295 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 10 10:01:48.798719 systemd-logind[1411]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 10 10:01:48.799568 systemd-logind[1411]: Watching system buttons on /dev/input/event2 (Power Button) May 10 10:01:48.813553 (ntainerd)[1497]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 10 10:01:48.830928 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 10 10:01:48.844080 systemd[1]: Started getty@tty1.service - Getty on tty1. May 10 10:01:48.847907 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 10 10:01:48.850135 systemd[1]: Reached target getty.target - Login Prompts. May 10 10:01:48.868022 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1367) May 10 10:01:49.152860 kernel: kvm_amd: TSC scaling supported May 10 10:01:49.152957 kernel: kvm_amd: Nested Virtualization enabled May 10 10:01:49.152976 kernel: kvm_amd: Nested Paging enabled May 10 10:01:49.152992 kernel: kvm_amd: LBR virtualization supported May 10 10:01:49.153047 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported May 10 10:01:49.153065 kernel: kvm_amd: Virtual GIF supported May 10 10:01:49.192749 kernel: EDAC MC: Ver: 3.0.0 May 10 10:01:49.295838 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:01:49.368871 containerd[1497]: time="2025-05-10T10:01:49Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 10 10:01:49.372081 containerd[1497]: time="2025-05-10T10:01:49.372013064Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 10 10:01:49.383492 containerd[1497]: time="2025-05-10T10:01:49.383436015Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.914µs" May 10 10:01:49.383492 containerd[1497]: time="2025-05-10T10:01:49.383473795Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 10 10:01:49.383492 containerd[1497]: time="2025-05-10T10:01:49.383495931Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 10 10:01:49.383940 containerd[1497]: time="2025-05-10T10:01:49.383874098Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 10 10:01:49.383940 containerd[1497]: time="2025-05-10T10:01:49.383912462Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 10 10:01:49.384833 containerd[1497]: time="2025-05-10T10:01:49.384757648Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 10 10:01:49.385036 containerd[1497]: time="2025-05-10T10:01:49.384997016Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 10 10:01:49.385036 containerd[1497]: time="2025-05-10T10:01:49.385023799Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 10 10:01:49.385497 containerd[1497]: time="2025-05-10T10:01:49.385441633Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 10 10:01:49.385497 containerd[1497]: time="2025-05-10T10:01:49.385472837Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 10 10:01:49.385497 containerd[1497]: time="2025-05-10T10:01:49.385488963Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 10 10:01:49.385591 containerd[1497]: time="2025-05-10T10:01:49.385503323Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 10 10:01:49.385672 containerd[1497]: time="2025-05-10T10:01:49.385638563Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 10 10:01:49.386024 containerd[1497]: time="2025-05-10T10:01:49.385988223Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 10 10:01:49.386058 containerd[1497]: time="2025-05-10T10:01:49.386035933Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 10 10:01:49.386058 containerd[1497]: time="2025-05-10T10:01:49.386050724Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 10 10:01:49.386141 containerd[1497]: time="2025-05-10T10:01:49.386099849Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 10 10:01:49.386522 containerd[1497]: time="2025-05-10T10:01:49.386490222Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 10 10:01:49.386624 containerd[1497]: time="2025-05-10T10:01:49.386599847Z" level=info msg="metadata content store policy set" policy=shared May 10 10:01:49.430797 containerd[1497]: time="2025-05-10T10:01:49.430615919Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 10 10:01:49.430982 containerd[1497]: time="2025-05-10T10:01:49.430828339Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 10 10:01:49.430982 containerd[1497]: time="2025-05-10T10:01:49.430853636Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 10 10:01:49.430982 containerd[1497]: time="2025-05-10T10:01:49.430874285Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 10 10:01:49.430982 containerd[1497]: time="2025-05-10T10:01:49.430901181Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 10 10:01:49.430982 containerd[1497]: time="2025-05-10T10:01:49.430942960Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 10 10:01:49.431121 containerd[1497]: time="2025-05-10T10:01:49.430993317Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 10 10:01:49.431121 containerd[1497]: time="2025-05-10T10:01:49.431021372Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 10 10:01:49.431121 containerd[1497]: time="2025-05-10T10:01:49.431035168Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 10 10:01:49.431121 containerd[1497]: time="2025-05-10T10:01:49.431049694Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 10 10:01:49.431121 containerd[1497]: time="2025-05-10T10:01:49.431061142Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 10 10:01:49.431121 containerd[1497]: time="2025-05-10T10:01:49.431090787Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 10 10:01:49.431489 containerd[1497]: time="2025-05-10T10:01:49.431443904Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 10 10:01:49.431523 containerd[1497]: time="2025-05-10T10:01:49.431492475Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 10 10:01:49.431523 containerd[1497]: time="2025-05-10T10:01:49.431515606Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431531711Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431549037Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431563038Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431587575Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431631541Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431650969Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431666069Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 10 10:01:49.431822 containerd[1497]: time="2025-05-10T10:01:49.431681106Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 10 10:01:49.431980 containerd[1497]: time="2025-05-10T10:01:49.431836975Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 10 10:01:49.431980 containerd[1497]: time="2025-05-10T10:01:49.431865133Z" level=info msg="Start snapshots syncer" May 10 10:01:49.431980 containerd[1497]: time="2025-05-10T10:01:49.431908010Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 10 10:01:49.432241 containerd[1497]: time="2025-05-10T10:01:49.432185773Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 10 10:01:49.432396 containerd[1497]: time="2025-05-10T10:01:49.432255147Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 10 10:01:49.432422 containerd[1497]: time="2025-05-10T10:01:49.432398367Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 10 10:01:49.432595 containerd[1497]: time="2025-05-10T10:01:49.432561200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 10 10:01:49.432595 containerd[1497]: time="2025-05-10T10:01:49.432590076Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 10 10:01:49.432640 containerd[1497]: time="2025-05-10T10:01:49.432618091Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 10 10:01:49.432640 containerd[1497]: time="2025-05-10T10:01:49.432630410Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 10 10:01:49.432700 containerd[1497]: time="2025-05-10T10:01:49.432643991Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 10 10:01:49.432700 containerd[1497]: time="2025-05-10T10:01:49.432655337Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 10 10:01:49.432700 containerd[1497]: time="2025-05-10T10:01:49.432666713Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 10 10:01:49.432800 containerd[1497]: time="2025-05-10T10:01:49.432771661Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 10 10:01:49.432823 containerd[1497]: time="2025-05-10T10:01:49.432800762Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 10 10:01:49.432878 containerd[1497]: time="2025-05-10T10:01:49.432856299Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 10 10:01:49.432928 containerd[1497]: time="2025-05-10T10:01:49.432909896Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 10 10:01:49.432957 containerd[1497]: time="2025-05-10T10:01:49.432928391Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 10 10:01:49.432957 containerd[1497]: time="2025-05-10T10:01:49.432943131Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 10 10:01:49.432957 containerd[1497]: time="2025-05-10T10:01:49.432953964Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 10 10:01:49.433016 containerd[1497]: time="2025-05-10T10:01:49.432962601Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 10 10:01:49.433016 containerd[1497]: time="2025-05-10T10:01:49.432973156Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 10 10:01:49.433016 containerd[1497]: time="2025-05-10T10:01:49.432986523Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 10 10:01:49.433016 containerd[1497]: time="2025-05-10T10:01:49.433002216Z" level=info msg="runtime interface created" May 10 10:01:49.433016 containerd[1497]: time="2025-05-10T10:01:49.433008843Z" level=info msg="created NRI interface" May 10 10:01:49.433016 containerd[1497]: time="2025-05-10T10:01:49.433017778Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 10 10:01:49.433134 containerd[1497]: time="2025-05-10T10:01:49.433029831Z" level=info msg="Connect containerd service" May 10 10:01:49.433194 containerd[1497]: time="2025-05-10T10:01:49.433141559Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 10 10:01:49.434250 containerd[1497]: time="2025-05-10T10:01:49.434211342Z" 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 10 10:01:49.638499 containerd[1497]: time="2025-05-10T10:01:49.638343266Z" level=info msg="Start subscribing containerd event" May 10 10:01:49.638499 containerd[1497]: time="2025-05-10T10:01:49.638421236Z" level=info msg="Start recovering state" May 10 10:01:49.638658 containerd[1497]: time="2025-05-10T10:01:49.638571072Z" level=info msg="Start event monitor" May 10 10:01:49.639070 containerd[1497]: time="2025-05-10T10:01:49.639010027Z" level=info msg="Start cni network conf syncer for default" May 10 10:01:49.639070 containerd[1497]: time="2025-05-10T10:01:49.639073882Z" level=info msg="Start streaming server" May 10 10:01:49.639168 containerd[1497]: time="2025-05-10T10:01:49.639094131Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 10 10:01:49.639168 containerd[1497]: time="2025-05-10T10:01:49.639123838Z" level=info msg="runtime interface starting up..." May 10 10:01:49.639168 containerd[1497]: time="2025-05-10T10:01:49.639104276Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 10 10:01:49.639368 containerd[1497]: time="2025-05-10T10:01:49.639327046Z" level=info msg=serving... address=/run/containerd/containerd.sock May 10 10:01:49.639937 containerd[1497]: time="2025-05-10T10:01:49.639398666Z" level=info msg="starting plugins..." May 10 10:01:49.639937 containerd[1497]: time="2025-05-10T10:01:49.639543815Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 10 10:01:49.639858 systemd[1]: Started containerd.service - containerd container runtime. May 10 10:01:49.640193 containerd[1497]: time="2025-05-10T10:01:49.640137038Z" level=info msg="containerd successfully booted in 0.271967s" May 10 10:01:50.386034 systemd-networkd[1394]: eth0: Gained IPv6LL May 10 10:01:50.390420 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 10 10:01:50.392339 systemd[1]: Reached target network-online.target - Network is Online. May 10 10:01:50.395301 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 10 10:01:50.397667 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 10 10:01:50.443120 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 10 10:01:50.445212 systemd[1]: coreos-metadata.service: Deactivated successfully. May 10 10:01:50.445488 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 10 10:01:50.449667 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... May 10 10:01:50.451041 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 10 10:01:50.465765 (-wrapper)[1545]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS May 10 10:01:50.743001 systemd[1]: Starting docker.service - Docker Application Container Engine... May 10 10:01:50.769173 (dockerd)[1557]: 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 10 10:01:51.439702 dockerd[1557]: time="2025-05-10T10:01:51.439559692Z" level=info msg="Starting up" May 10 10:01:51.442825 dockerd[1557]: time="2025-05-10T10:01:51.442789806Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 10 10:01:51.623263 dockerd[1557]: time="2025-05-10T10:01:51.623186313Z" level=info msg="Loading containers: start." May 10 10:01:51.637715 kernel: Initializing XFRM netlink socket May 10 10:01:52.158406 systemd-networkd[1394]: docker0: Link UP May 10 10:01:52.166910 dockerd[1557]: time="2025-05-10T10:01:52.166838171Z" level=info msg="Loading containers: done." May 10 10:01:52.223860 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1125221289-merged.mount: Deactivated successfully. May 10 10:01:52.227580 dockerd[1557]: time="2025-05-10T10:01:52.227518069Z" 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 10 10:01:52.227669 dockerd[1557]: time="2025-05-10T10:01:52.227648512Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 10 10:01:52.227886 dockerd[1557]: time="2025-05-10T10:01:52.227853313Z" level=info msg="Initializing buildkit" May 10 10:01:52.282030 dockerd[1557]: time="2025-05-10T10:01:52.281950035Z" level=info msg="Completed buildkit initialization" May 10 10:01:52.290261 dockerd[1557]: time="2025-05-10T10:01:52.290182221Z" level=info msg="Daemon has completed initialization" May 10 10:01:52.290398 dockerd[1557]: time="2025-05-10T10:01:52.290262003Z" level=info msg="API listen on /run/docker.sock" May 10 10:01:52.290505 systemd[1]: Started docker.service - Docker Application Container Engine. May 10 10:01:52.294776 etcd-wrapper[1549]: Error response from daemon: No such container: etcd-member May 10 10:01:52.316882 etcd-wrapper[1748]: Error response from daemon: No such container: etcd-member May 10 10:01:52.355856 etcd-wrapper[1769]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally May 10 10:01:53.463309 etcd-wrapper[1769]: v3.5.16: Pulling from coreos/etcd May 10 10:01:53.753024 etcd-wrapper[1769]: 804c8aba2cc6: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: 2ae710cd8bfe: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: d462aa345367: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: 0f8b424aa0b9: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: d557676654e5: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: c8022d07192e: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: d858cbc252ad: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: 1069fc2daed1: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: b40161cd83fc: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: 5318d93a3a65: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: 307c1adadb60: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: fbb01d9e9dc9: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: fbfea02ac3cf: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: 8c26e4bf18e2: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: 1e59a65f8816: Pulling fs layer May 10 10:01:53.753024 etcd-wrapper[1769]: ffbd4ca5f0bd: Pulling fs layer May 10 10:01:53.753682 etcd-wrapper[1769]: d557676654e5: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: b40161cd83fc: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: 5318d93a3a65: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: c8022d07192e: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: 307c1adadb60: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: fbb01d9e9dc9: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: d858cbc252ad: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: fbfea02ac3cf: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: 1069fc2daed1: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: 8c26e4bf18e2: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: 1e59a65f8816: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: ffbd4ca5f0bd: Waiting May 10 10:01:53.753682 etcd-wrapper[1769]: 0f8b424aa0b9: Waiting May 10 10:01:53.994601 etcd-wrapper[1769]: d462aa345367: Verifying Checksum May 10 10:01:53.994601 etcd-wrapper[1769]: d462aa345367: Download complete May 10 10:01:54.027000 etcd-wrapper[1769]: 2ae710cd8bfe: Verifying Checksum May 10 10:01:54.027000 etcd-wrapper[1769]: 2ae710cd8bfe: Download complete May 10 10:01:54.032919 etcd-wrapper[1769]: 804c8aba2cc6: Verifying Checksum May 10 10:01:54.032919 etcd-wrapper[1769]: 804c8aba2cc6: Download complete May 10 10:01:54.070853 etcd-wrapper[1769]: 804c8aba2cc6: Pull complete May 10 10:01:54.093680 systemd[1]: var-lib-docker-overlay2-6e1cfb41cd505f0e985a14fad1ed5d3ec12839ea5ba98036252630c8f6262b1d-merged.mount: Deactivated successfully. May 10 10:01:54.138557 etcd-wrapper[1769]: 2ae710cd8bfe: Pull complete May 10 10:01:54.257660 etcd-wrapper[1769]: d557676654e5: Verifying Checksum May 10 10:01:54.257660 etcd-wrapper[1769]: d557676654e5: Download complete May 10 10:01:54.293219 etcd-wrapper[1769]: c8022d07192e: Download complete May 10 10:01:54.350173 etcd-wrapper[1769]: 0f8b424aa0b9: Verifying Checksum May 10 10:01:54.350173 etcd-wrapper[1769]: 0f8b424aa0b9: Download complete May 10 10:01:54.474849 etcd-wrapper[1769]: d858cbc252ad: Verifying Checksum May 10 10:01:54.474849 etcd-wrapper[1769]: d858cbc252ad: Download complete May 10 10:01:54.512343 etcd-wrapper[1769]: 1069fc2daed1: Download complete May 10 10:01:54.558973 etcd-wrapper[1769]: b40161cd83fc: Verifying Checksum May 10 10:01:54.558973 etcd-wrapper[1769]: b40161cd83fc: Download complete May 10 10:01:54.701537 etcd-wrapper[1769]: 5318d93a3a65: Verifying Checksum May 10 10:01:54.701537 etcd-wrapper[1769]: 5318d93a3a65: Download complete May 10 10:01:54.746255 etcd-wrapper[1769]: 307c1adadb60: Download complete May 10 10:01:54.765124 etcd-wrapper[1769]: d462aa345367: Pull complete May 10 10:01:54.806975 etcd-wrapper[1769]: 0f8b424aa0b9: Pull complete May 10 10:01:54.849662 etcd-wrapper[1769]: d557676654e5: Pull complete May 10 10:01:54.883097 etcd-wrapper[1769]: fbb01d9e9dc9: Verifying Checksum May 10 10:01:54.883097 etcd-wrapper[1769]: fbb01d9e9dc9: Download complete May 10 10:01:54.903614 etcd-wrapper[1769]: c8022d07192e: Pull complete May 10 10:01:54.937241 systemd[1]: var-lib-docker-overlay2-c7e18378bd2f5b5a28b003255b782181cb040e477223ee215348f37f2fe9c8ad-merged.mount: Deactivated successfully. May 10 10:01:54.938431 etcd-wrapper[1769]: d858cbc252ad: Pull complete May 10 10:01:54.961878 systemd[1]: var-lib-docker-overlay2-7013be5a209fa2df5c046b2a7fa4cbc74a958891185580219fe73761abb33a88-merged.mount: Deactivated successfully. May 10 10:01:54.983377 etcd-wrapper[1769]: fbfea02ac3cf: Verifying Checksum May 10 10:01:54.983377 etcd-wrapper[1769]: fbfea02ac3cf: Download complete May 10 10:01:54.994831 etcd-wrapper[1769]: 1069fc2daed1: Pull complete May 10 10:01:55.000478 etcd-wrapper[1769]: 8c26e4bf18e2: Verifying Checksum May 10 10:01:55.000478 etcd-wrapper[1769]: 8c26e4bf18e2: Download complete May 10 10:01:55.018640 systemd[1]: var-lib-docker-overlay2-92ac8d04a5f116ec8e3badafad65647a6afe608593f2971a87c60e25ead4cf8b-merged.mount: Deactivated successfully. May 10 10:01:55.084470 etcd-wrapper[1769]: b40161cd83fc: Pull complete May 10 10:01:55.106499 etcd-wrapper[1769]: 1e59a65f8816: Verifying Checksum May 10 10:01:55.106499 etcd-wrapper[1769]: 1e59a65f8816: Download complete May 10 10:01:55.115302 etcd-wrapper[1769]: 5318d93a3a65: Pull complete May 10 10:01:55.149129 etcd-wrapper[1769]: 307c1adadb60: Pull complete May 10 10:01:55.238424 etcd-wrapper[1769]: ffbd4ca5f0bd: Verifying Checksum May 10 10:01:55.238568 etcd-wrapper[1769]: ffbd4ca5f0bd: Download complete May 10 10:01:55.320227 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 10 10:01:55.322648 systemd[1]: Started sshd@0-10.0.0.110:22-10.0.0.1:43600.service - OpenSSH per-connection server daemon (10.0.0.1:43600). May 10 10:01:55.451338 etcd-wrapper[1769]: fbb01d9e9dc9: Pull complete May 10 10:01:55.483267 sshd[1849]: Accepted publickey for core from 10.0.0.1 port 43600 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:01:55.486575 sshd-session[1849]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:01:55.496555 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 10 10:01:55.501968 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 10 10:01:55.510907 systemd-logind[1411]: New session 1 of user core. May 10 10:01:55.532124 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 10 10:01:55.535895 systemd[1]: Starting user@500.service - User Manager for UID 500... May 10 10:01:55.551481 (systemd)[1858]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 10 10:01:55.554196 systemd-logind[1411]: New session c1 of user core. May 10 10:01:55.780747 etcd-wrapper[1769]: fbfea02ac3cf: Pull complete May 10 10:01:55.780996 systemd[1858]: Queued start job for default target default.target. May 10 10:01:55.790628 systemd[1858]: Created slice app.slice - User Application Slice. May 10 10:01:55.790671 systemd[1858]: Reached target paths.target - Paths. May 10 10:01:55.790778 systemd[1858]: Reached target timers.target - Timers. May 10 10:01:55.792913 systemd[1858]: Starting dbus.socket - D-Bus User Message Bus Socket... May 10 10:01:55.808673 systemd[1858]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 10 10:01:55.808876 systemd[1858]: Reached target sockets.target - Sockets. May 10 10:01:55.808954 systemd[1858]: Reached target basic.target - Basic System. May 10 10:01:55.809041 systemd[1858]: Reached target default.target - Main User Target. May 10 10:01:55.809100 systemd[1858]: Startup finished in 247ms. May 10 10:01:55.809651 systemd[1]: Started user@500.service - User Manager for UID 500. May 10 10:01:55.820728 systemd[1]: Started session-1.scope - Session 1 of User core. May 10 10:01:55.890380 systemd[1]: Started sshd@1-10.0.0.110:22-10.0.0.1:43604.service - OpenSSH per-connection server daemon (10.0.0.1:43604). May 10 10:01:55.948699 sshd[1874]: Accepted publickey for core from 10.0.0.1 port 43604 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:01:55.951262 sshd-session[1874]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:01:55.952567 systemd[1]: var-lib-docker-overlay2-1a16351e813e7d1a9ec1042b32444853d2bedd9aee7829d82f574fec7f553665-merged.mount: Deactivated successfully. May 10 10:01:55.959098 systemd-logind[1411]: New session 2 of user core. May 10 10:01:55.973012 systemd[1]: Started session-2.scope - Session 2 of User core. May 10 10:01:56.030251 etcd-wrapper[1769]: 8c26e4bf18e2: Pull complete May 10 10:01:56.032206 sshd[1876]: Connection closed by 10.0.0.1 port 43604 May 10 10:01:56.032370 sshd-session[1874]: pam_unix(sshd:session): session closed for user core May 10 10:01:56.048207 systemd[1]: var-lib-docker-overlay2-1e34c0c73cacee3c873a5e0388912f45186c96ad1adc429ccc4d0b379279df8d-merged.mount: Deactivated successfully. May 10 10:01:56.049547 systemd[1]: sshd@1-10.0.0.110:22-10.0.0.1:43604.service: Deactivated successfully. May 10 10:01:56.052419 systemd[1]: session-2.scope: Deactivated successfully. May 10 10:01:56.054835 systemd-logind[1411]: Session 2 logged out. Waiting for processes to exit. May 10 10:01:56.056984 systemd[1]: Started sshd@2-10.0.0.110:22-10.0.0.1:43606.service - OpenSSH per-connection server daemon (10.0.0.1:43606). May 10 10:01:56.058095 systemd-logind[1411]: Removed session 2. May 10 10:01:56.060176 etcd-wrapper[1769]: 1e59a65f8816: Pull complete May 10 10:01:56.091141 etcd-wrapper[1769]: ffbd4ca5f0bd: Pull complete May 10 10:01:56.103307 etcd-wrapper[1769]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 May 10 10:01:56.107976 etcd-wrapper[1769]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 May 10 10:01:56.111977 sshd[1885]: Accepted publickey for core from 10.0.0.1 port 43606 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:01:56.114467 sshd-session[1885]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:01:56.120659 systemd-logind[1411]: New session 3 of user core. May 10 10:01:56.130868 systemd[1]: Started session-3.scope - Session 3 of User core. May 10 10:01:56.198295 sshd[1893]: Connection closed by 10.0.0.1 port 43606 May 10 10:01:56.198897 sshd-session[1885]: pam_unix(sshd:session): session closed for user core May 10 10:01:56.205218 systemd[1]: sshd@2-10.0.0.110:22-10.0.0.1:43606.service: Deactivated successfully. May 10 10:01:56.210154 containerd[1497]: time="2025-05-10T10:01:56.208325529Z" level=info msg="connecting to shim 348247febeebe3008ce39e802c6afcd76a866a6acdcb0d18719c26f3d09dbc7d" address="unix:///run/containerd/s/c7be2bae4cdb54e9dc10fb9df9c1456689464e18d2351b9d3ac39fd2aa616aee" namespace=moby protocol=ttrpc version=3 May 10 10:01:56.209134 systemd[1]: session-3.scope: Deactivated successfully. May 10 10:01:56.210346 systemd-logind[1411]: Session 3 logged out. Waiting for processes to exit. May 10 10:01:56.212064 systemd-logind[1411]: Removed session 3. May 10 10:01:56.253941 systemd[1]: Started docker-348247febeebe3008ce39e802c6afcd76a866a6acdcb0d18719c26f3d09dbc7d.scope - libcontainer container 348247febeebe3008ce39e802c6afcd76a866a6acdcb0d18719c26f3d09dbc7d. May 10 10:01:56.290212 systemd-resolved[1307]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 10 10:01:56.419281 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.418779Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} May 10 10:01:56.419281 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.419014Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"66ea1ce63deb4f97b980abcc1572d39e"} May 10 10:01:56.419281 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:01:56.419044Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} May 10 10:01:56.419281 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:01:56.419072Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} May 10 10:01:56.419281 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:01:56.419079Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} May 10 10:01:56.419281 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:01:56.419090Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} May 10 10:01:56.419853 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:01:56.419156Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 10 10:01:56.419853 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.419193Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.0.0.110:2379"]} May 10 10:01:56.419853 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:01:56.419306Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} May 10 10:01:56.419853 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.419325Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} May 10 10:01:56.420238 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.420082Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} May 10 10:01:56.420459 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.420260Z","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":"66ea1ce63deb4f97b980abcc1572d39e","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.110:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"66ea1ce63deb4f97b980abcc1572d39e=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 10 10:01:56.424429 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.424219Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.47167ms"} May 10 10:01:56.430903 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.430630Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} May 10 10:01:56.431066 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.430788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} May 10 10:01:56.431066 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.430854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} May 10 10:01:56.431066 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.430869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} May 10 10:01:56.431066 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.430880Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} May 10 10:01:56.431066 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.430923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 10 10:01:56.438317 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:01:56.438028Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} May 10 10:01:56.439896 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.439639Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} May 10 10:01:56.441228 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.440991Z","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 10 10:01:56.445039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.444449Z","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 10 10:01:56.445039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.444643Z","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 10 10:01:56.445039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.444754Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 10 10:01:56.445039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.444775Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} May 10 10:01:56.445039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.444872Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} May 10 10:01:56.445039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.444883Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} May 10 10:01:56.446548 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.446303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} May 10 10:01:56.447251 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.446816Z","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 10 10:01:56.448037 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.447794Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} May 10 10:01:56.448037 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.447856Z","caller":"embed/etcd.go:280","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.110:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} May 10 10:01:56.448037 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.447831Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} May 10 10:01:56.532809 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.532141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} May 10 10:01:56.532809 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.532208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} May 10 10:01:56.532809 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.532266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} May 10 10:01:56.532809 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.532289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} May 10 10:01:56.532809 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.532301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} May 10 10:01:56.532809 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.532324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} May 10 10:01:56.532809 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.532333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} May 10 10:01:56.598180 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.597030Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:66ea1ce63deb4f97b980abcc1572d39e ClientURLs:[http://10.0.0.110:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} May 10 10:01:56.598180 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.597054Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} May 10 10:01:56.598180 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.597094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} May 10 10:01:56.598180 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.597280Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} May 10 10:01:56.598180 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.597444Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} May 10 10:01:56.598014 systemd[1]: Started etcd-member.service - etcd (System Application Container). May 10 10:01:56.598545 systemd[1]: Reached target multi-user.target - Multi-User System. May 10 10:01:56.598914 systemd[1]: Startup finished in 3.443s (kernel) + 4.399s (initrd) + 10.525s (userspace) = 18.368s. May 10 10:01:56.599115 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.598000Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} May 10 10:01:56.599115 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.598450Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} May 10 10:01:56.628518 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.628133Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} May 10 10:01:56.628518 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.628241Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} May 10 10:01:56.628518 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:01:56.628290Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} May 10 10:01:56.939159 systemd[1]: var-lib-docker-overlay2-34c861eef73e6d2b062e61edcea1bd1164c7efb600caa0bacc452b93ba728f11-merged.mount: Deactivated successfully. May 10 10:02:06.256150 systemd[1]: Started sshd@3-10.0.0.110:22-10.0.0.1:41038.service - OpenSSH per-connection server daemon (10.0.0.1:41038). May 10 10:02:06.314581 sshd[1953]: Accepted publickey for core from 10.0.0.1 port 41038 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:02:06.316357 sshd-session[1953]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:02:06.320833 systemd-logind[1411]: New session 4 of user core. May 10 10:02:06.334916 systemd[1]: Started session-4.scope - Session 4 of User core. May 10 10:02:06.391406 sshd[1955]: Connection closed by 10.0.0.1 port 41038 May 10 10:02:06.391881 sshd-session[1953]: pam_unix(sshd:session): session closed for user core May 10 10:02:06.409977 systemd[1]: sshd@3-10.0.0.110:22-10.0.0.1:41038.service: Deactivated successfully. May 10 10:02:06.412109 systemd[1]: session-4.scope: Deactivated successfully. May 10 10:02:06.413988 systemd-logind[1411]: Session 4 logged out. Waiting for processes to exit. May 10 10:02:06.415335 systemd[1]: Started sshd@4-10.0.0.110:22-10.0.0.1:41042.service - OpenSSH per-connection server daemon (10.0.0.1:41042). May 10 10:02:06.416436 systemd-logind[1411]: Removed session 4. May 10 10:02:06.472122 sshd[1960]: Accepted publickey for core from 10.0.0.1 port 41042 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:02:06.473774 sshd-session[1960]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:02:06.478589 systemd-logind[1411]: New session 5 of user core. May 10 10:02:06.491819 systemd[1]: Started session-5.scope - Session 5 of User core. May 10 10:02:06.541821 sshd[1963]: Connection closed by 10.0.0.1 port 41042 May 10 10:02:06.542006 sshd-session[1960]: pam_unix(sshd:session): session closed for user core May 10 10:02:06.555630 systemd[1]: sshd@4-10.0.0.110:22-10.0.0.1:41042.service: Deactivated successfully. May 10 10:02:06.557641 systemd[1]: session-5.scope: Deactivated successfully. May 10 10:02:06.559621 systemd-logind[1411]: Session 5 logged out. Waiting for processes to exit. May 10 10:02:06.561050 systemd[1]: Started sshd@5-10.0.0.110:22-10.0.0.1:41054.service - OpenSSH per-connection server daemon (10.0.0.1:41054). May 10 10:02:06.562025 systemd-logind[1411]: Removed session 5. May 10 10:02:06.627323 sshd[1968]: Accepted publickey for core from 10.0.0.1 port 41054 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:02:06.628927 sshd-session[1968]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:02:06.633500 systemd-logind[1411]: New session 6 of user core. May 10 10:02:06.642830 systemd[1]: Started session-6.scope - Session 6 of User core. May 10 10:02:06.698343 sshd[1971]: Connection closed by 10.0.0.1 port 41054 May 10 10:02:06.698871 sshd-session[1968]: pam_unix(sshd:session): session closed for user core May 10 10:02:06.714710 systemd[1]: sshd@5-10.0.0.110:22-10.0.0.1:41054.service: Deactivated successfully. May 10 10:02:06.716848 systemd[1]: session-6.scope: Deactivated successfully. May 10 10:02:06.718773 systemd-logind[1411]: Session 6 logged out. Waiting for processes to exit. May 10 10:02:06.720150 systemd[1]: Started sshd@6-10.0.0.110:22-10.0.0.1:41064.service - OpenSSH per-connection server daemon (10.0.0.1:41064). May 10 10:02:06.721113 systemd-logind[1411]: Removed session 6. May 10 10:02:06.772117 sshd[1976]: Accepted publickey for core from 10.0.0.1 port 41064 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:02:06.773796 sshd-session[1976]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:02:06.778878 systemd-logind[1411]: New session 7 of user core. May 10 10:02:06.793004 systemd[1]: Started session-7.scope - Session 7 of User core. May 10 10:02:06.857202 sudo[1980]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 10 10:02:06.857647 sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 10 10:02:06.878825 sudo[1980]: pam_unix(sudo:session): session closed for user root May 10 10:02:06.881304 sshd[1979]: Connection closed by 10.0.0.1 port 41064 May 10 10:02:06.881831 sshd-session[1976]: pam_unix(sshd:session): session closed for user core May 10 10:02:06.893809 systemd[1]: sshd@6-10.0.0.110:22-10.0.0.1:41064.service: Deactivated successfully. May 10 10:02:06.896509 systemd[1]: session-7.scope: Deactivated successfully. May 10 10:02:06.899188 systemd-logind[1411]: Session 7 logged out. Waiting for processes to exit. May 10 10:02:06.901325 systemd[1]: Started sshd@7-10.0.0.110:22-10.0.0.1:47032.service - OpenSSH per-connection server daemon (10.0.0.1:47032). May 10 10:02:06.902634 systemd-logind[1411]: Removed session 7. May 10 10:02:06.959524 sshd[1985]: Accepted publickey for core from 10.0.0.1 port 47032 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:02:06.961428 sshd-session[1985]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:02:06.966562 systemd-logind[1411]: New session 8 of user core. May 10 10:02:06.979889 systemd[1]: Started session-8.scope - Session 8 of User core. May 10 10:02:07.036378 sudo[1990]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 10 10:02:07.036780 sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 10 10:02:07.041149 sudo[1990]: pam_unix(sudo:session): session closed for user root May 10 10:02:07.048968 sudo[1989]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 10 10:02:07.049403 sudo[1989]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 10 10:02:07.060565 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 10 10:02:07.111110 augenrules[2012]: No rules May 10 10:02:07.113008 systemd[1]: audit-rules.service: Deactivated successfully. May 10 10:02:07.113330 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 10 10:02:07.114442 sudo[1989]: pam_unix(sudo:session): session closed for user root May 10 10:02:07.116024 sshd[1988]: Connection closed by 10.0.0.1 port 47032 May 10 10:02:07.116456 sshd-session[1985]: pam_unix(sshd:session): session closed for user core May 10 10:02:07.127499 systemd[1]: sshd@7-10.0.0.110:22-10.0.0.1:47032.service: Deactivated successfully. May 10 10:02:07.129480 systemd[1]: session-8.scope: Deactivated successfully. May 10 10:02:07.131095 systemd-logind[1411]: Session 8 logged out. Waiting for processes to exit. May 10 10:02:07.132730 systemd[1]: Started sshd@8-10.0.0.110:22-10.0.0.1:47046.service - OpenSSH per-connection server daemon (10.0.0.1:47046). May 10 10:02:07.133668 systemd-logind[1411]: Removed session 8. May 10 10:02:07.184666 sshd[2020]: Accepted publickey for core from 10.0.0.1 port 47046 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:02:07.186215 sshd-session[2020]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:02:07.190813 systemd-logind[1411]: New session 9 of user core. May 10 10:02:07.200825 systemd[1]: Started session-9.scope - Session 9 of User core. May 10 10:02:07.252568 sshd[2023]: Connection closed by 10.0.0.1 port 47046 May 10 10:02:07.252950 sshd-session[2020]: pam_unix(sshd:session): session closed for user core May 10 10:02:07.262962 systemd[1]: sshd@8-10.0.0.110:22-10.0.0.1:47046.service: Deactivated successfully. May 10 10:02:07.265147 systemd[1]: session-9.scope: Deactivated successfully. May 10 10:02:07.267105 systemd-logind[1411]: Session 9 logged out. Waiting for processes to exit. May 10 10:02:07.268391 systemd[1]: Started sshd@9-10.0.0.110:22-10.0.0.1:47058.service - OpenSSH per-connection server daemon (10.0.0.1:47058). May 10 10:02:07.269570 systemd-logind[1411]: Removed session 9. May 10 10:02:07.325760 sshd[2030]: Accepted publickey for core from 10.0.0.1 port 47058 ssh2: RSA SHA256:ZOMyKPM9vG3Y5Dtmxr1HvCP8ZBfjY8jTU8Db0jmo1gs May 10 10:02:07.327313 sshd-session[2030]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:02:07.332660 systemd-logind[1411]: New session 10 of user core. May 10 10:02:07.345855 systemd[1]: Started session-10.scope - Session 10 of User core. May 10 10:02:07.475522 sshd[2033]: Connection closed by 10.0.0.1 port 47058 May 10 10:02:07.475850 sshd-session[2030]: pam_unix(sshd:session): session closed for user core May 10 10:02:07.480373 systemd[1]: sshd@9-10.0.0.110:22-10.0.0.1:47058.service: Deactivated successfully. May 10 10:02:07.482436 systemd[1]: session-10.scope: Deactivated successfully. May 10 10:02:07.483273 systemd-logind[1411]: Session 10 logged out. Waiting for processes to exit. May 10 10:02:07.484303 systemd-logind[1411]: Removed session 10. May 10 10:02:33.235351 update_engine[1412]: I20250510 10:02:33.235244 1412 update_attempter.cc:509] Updating boot flags... May 10 10:02:33.269729 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2051) May 10 10:02:33.317938 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2051) May 10 10:02:33.350718 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2051) May 10 10:03:03.428586 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.427811Z","caller":"traceutil/trace.go:171","msg":"trace[834269888] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"237.89095ms","start":"2025-05-10T10:03:03.189894Z","end":"2025-05-10T10:03:03.427785Z","steps":["trace[834269888] 'process raft request' (duration: 149.148694ms)","trace[834269888] 'compare' (duration: 88.566735ms)"],"step_count":2} May 10 10:03:03.428586 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.427871Z","caller":"traceutil/trace.go:171","msg":"trace[119234839] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"194.612711ms","start":"2025-05-10T10:03:03.233246Z","end":"2025-05-10T10:03:03.427859Z","steps":["trace[119234839] 'process raft request' (duration: 194.595428ms)"],"step_count":1} May 10 10:03:03.428586 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.427931Z","caller":"traceutil/trace.go:171","msg":"trace[2027287086] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"235.470486ms","start":"2025-05-10T10:03:03.192456Z","end":"2025-05-10T10:03:03.427927Z","steps":["trace[2027287086] 'process raft request' (duration: 235.368948ms)"],"step_count":1} May 10 10:03:03.428586 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.427914Z","caller":"traceutil/trace.go:171","msg":"trace[836752157] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"235.935138ms","start":"2025-05-10T10:03:03.191965Z","end":"2025-05-10T10:03:03.427900Z","steps":["trace[836752157] 'process raft request' (duration: 235.847468ms)"],"step_count":1} May 10 10:03:03.428586 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.427982Z","caller":"traceutil/trace.go:171","msg":"trace[1306768449] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"236.353191ms","start":"2025-05-10T10:03:03.191623Z","end":"2025-05-10T10:03:03.427977Z","steps":["trace[1306768449] 'process raft request' (duration: 236.157341ms)"],"step_count":1} May 10 10:03:03.429408 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.427998Z","caller":"traceutil/trace.go:171","msg":"trace[1781801453] linearizableReadLoop","detail":"{readStateIndex:21; appliedIndex:14; }","duration":"197.157348ms","start":"2025-05-10T10:03:03.230800Z","end":"2025-05-10T10:03:03.427957Z","steps":["trace[1781801453] 'read index received' (duration: 108.253066ms)","trace[1781801453] 'applied index is now lower than readState.Index' (duration: 88.90357ms)"],"step_count":2} May 10 10:03:03.429408 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.428002Z","caller":"traceutil/trace.go:171","msg":"trace[201196635] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"236.0443ms","start":"2025-05-10T10:03:03.191935Z","end":"2025-05-10T10:03:03.427980Z","steps":["trace[201196635] 'process raft request' (duration: 235.857779ms)"],"step_count":1} May 10 10:03:03.429408 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.427905Z","caller":"traceutil/trace.go:171","msg":"trace[1210783443] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"237.944224ms","start":"2025-05-10T10:03:03.189940Z","end":"2025-05-10T10:03:03.427884Z","steps":["trace[1210783443] 'process raft request' (duration: 237.802599ms)"],"step_count":1} May 10 10:03:03.429408 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.428024Z","caller":"traceutil/trace.go:171","msg":"trace[1281847669] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"237.034195ms","start":"2025-05-10T10:03:03.190986Z","end":"2025-05-10T10:03:03.428020Z","steps":["trace[1281847669] 'process raft request' (duration: 236.777757ms)"],"step_count":1} May 10 10:03:03.429408 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:03.428085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.49497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} May 10 10:03:03.429588 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:03.428111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.304885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} May 10 10:03:03.429588 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.428134Z","caller":"traceutil/trace.go:171","msg":"trace[915077458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17; }","duration":"196.575806ms","start":"2025-05-10T10:03:03.231550Z","end":"2025-05-10T10:03:03.428125Z","steps":["trace[915077458] 'agreement among raft nodes before linearized reading' (duration: 196.505631ms)"],"step_count":1} May 10 10:03:03.429588 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:03.428138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.420612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" ","response":"range_response_count:0 size:4"} May 10 10:03:03.429588 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:03.428162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.847254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} May 10 10:03:03.429588 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.428151Z","caller":"traceutil/trace.go:171","msg":"trace[2049700220] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:17; }","duration":"210.381213ms","start":"2025-05-10T10:03:03.217758Z","end":"2025-05-10T10:03:03.428139Z","steps":["trace[2049700220] 'agreement among raft nodes before linearized reading' (duration: 210.316829ms)"],"step_count":1} May 10 10:03:03.429801 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.428196Z","caller":"traceutil/trace.go:171","msg":"trace[400049393] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:17; }","duration":"137.897341ms","start":"2025-05-10T10:03:03.290285Z","end":"2025-05-10T10:03:03.428182Z","steps":["trace[400049393] 'agreement among raft nodes before linearized reading' (duration: 137.848988ms)"],"step_count":1} May 10 10:03:03.429801 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.428184Z","caller":"traceutil/trace.go:171","msg":"trace[1247943122] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:0; response_revision:17; }","duration":"236.545456ms","start":"2025-05-10T10:03:03.191626Z","end":"2025-05-10T10:03:03.428171Z","steps":["trace[1247943122] 'agreement among raft nodes before linearized reading' (duration: 236.417717ms)"],"step_count":1} May 10 10:03:03.765833 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:03.765152Z","caller":"traceutil/trace.go:171","msg":"trace[1953351265] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"101.829108ms","start":"2025-05-10T10:03:03.663299Z","end":"2025-05-10T10:03:03.765128Z","steps":["trace[1953351265] 'process raft request' (duration: 45.68129ms)","trace[1953351265] 'compare' (duration: 56.014819ms)"],"step_count":2} May 10 10:03:04.166624 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:04.166102Z","caller":"traceutil/trace.go:171","msg":"trace[60423963] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"111.887303ms","start":"2025-05-10T10:03:04.054192Z","end":"2025-05-10T10:03:04.166079Z","steps":["trace[60423963] 'process raft request' (duration: 29.974753ms)","trace[60423963] 'compare' (duration: 81.76292ms)"],"step_count":2} May 10 10:03:04.432039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:04.431411Z","caller":"traceutil/trace.go:171","msg":"trace[1743747142] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"131.816825ms","start":"2025-05-10T10:03:04.299575Z","end":"2025-05-10T10:03:04.431391Z","steps":["trace[1743747142] 'process raft request' (duration: 104.481898ms)","trace[1743747142] 'compare' (duration: 27.215454ms)"],"step_count":2} May 10 10:03:06.871078 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:06.870698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.03246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} May 10 10:03:06.871078 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:06.870768Z","caller":"traceutil/trace.go:171","msg":"trace[1467755857] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:257; }","duration":"119.144548ms","start":"2025-05-10T10:03:06.751602Z","end":"2025-05-10T10:03:06.870747Z","steps":["trace[1467755857] 'range keys from in-memory index tree' (duration: 118.950812ms)"],"step_count":1} May 10 10:03:07.778322 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:07.777950Z","caller":"traceutil/trace.go:171","msg":"trace[511980728] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"117.350042ms","start":"2025-05-10T10:03:07.660574Z","end":"2025-05-10T10:03:07.777924Z","steps":["trace[511980728] 'process raft request' (duration: 63.37422ms)","trace[511980728] 'compare' (duration: 53.755514ms)"],"step_count":2} May 10 10:03:08.515274 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:08.514862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.433688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 10 10:03:08.515274 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:08.514954Z","caller":"traceutil/trace.go:171","msg":"trace[1255510240] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"312.996444ms","start":"2025-05-10T10:03:08.201947Z","end":"2025-05-10T10:03:08.514943Z","steps":["trace[1255510240] 'read index received' (duration: 145.129367ms)","trace[1255510240] 'applied index is now lower than readState.Index' (duration: 167.865875ms)"],"step_count":2} May 10 10:03:08.516022 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:08.515052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.085828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} May 10 10:03:08.516022 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:08.515128Z","caller":"traceutil/trace.go:171","msg":"trace[482214916] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:350; }","duration":"313.194948ms","start":"2025-05-10T10:03:08.201916Z","end":"2025-05-10T10:03:08.515111Z","steps":["trace[482214916] 'agreement among raft nodes before linearized reading' (duration: 313.068744ms)"],"step_count":1} May 10 10:03:08.516022 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:08.515161Z","caller":"traceutil/trace.go:171","msg":"trace[1820889420] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"353.035915ms","start":"2025-05-10T10:03:08.161959Z","end":"2025-05-10T10:03:08.514995Z","steps":["trace[1820889420] 'process raft request' (duration: 185.105805ms)","trace[1820889420] 'compare' (duration: 167.322493ms)"],"step_count":2} May 10 10:03:08.516022 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:08.515163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:08.201898Z","time spent":"313.253893ms","remote":"10.0.0.112:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} May 10 10:03:08.516161 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:08.515426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:08.161947Z","time spent":"353.447391ms","remote":"10.0.0.112:40030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":52342,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:08.519125 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:08.518733Z","caller":"traceutil/trace.go:171","msg":"trace[1888628318] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"311.880743ms","start":"2025-05-10T10:03:08.206833Z","end":"2025-05-10T10:03:08.518714Z","steps":["trace[1888628318] 'process raft request' (duration: 311.753707ms)"],"step_count":1} May 10 10:03:08.519337 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:08.518825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:08.206820Z","time spent":"311.958112ms","remote":"10.0.0.112:40276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:08.519337 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:08.519003Z","caller":"traceutil/trace.go:171","msg":"trace[1204829925] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"247.284878ms","start":"2025-05-10T10:03:08.271707Z","end":"2025-05-10T10:03:08.518992Z","steps":["trace[1204829925] 'process raft request' (duration: 247.078037ms)"],"step_count":1} May 10 10:03:09.149278 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:09.148766Z","caller":"traceutil/trace.go:171","msg":"trace[584121824] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"108.811048ms","start":"2025-05-10T10:03:09.039934Z","end":"2025-05-10T10:03:09.148745Z","steps":["trace[584121824] 'process raft request' (duration: 108.456711ms)"],"step_count":1} May 10 10:03:29.981558 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:29.981159Z","caller":"traceutil/trace.go:171","msg":"trace[1715231819] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"111.578614ms","start":"2025-05-10T10:03:29.869557Z","end":"2025-05-10T10:03:29.981135Z","steps":["trace[1715231819] 'process raft request' (duration: 111.455021ms)"],"step_count":1} May 10 10:03:32.658298 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:32.657699Z","caller":"traceutil/trace.go:171","msg":"trace[488154347] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"121.178186ms","start":"2025-05-10T10:03:32.536483Z","end":"2025-05-10T10:03:32.657661Z","steps":["trace[488154347] 'read index received' (duration: 83.363901ms)","trace[488154347] 'applied index is now lower than readState.Index' (duration: 37.813664ms)"],"step_count":2} May 10 10:03:32.658298 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:32.657748Z","caller":"traceutil/trace.go:171","msg":"trace[2089705699] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"144.297966ms","start":"2025-05-10T10:03:32.513421Z","end":"2025-05-10T10:03:32.657719Z","steps":["trace[2089705699] 'process raft request' (duration: 106.450107ms)","trace[2089705699] 'compare' (duration: 37.642471ms)"],"step_count":2} May 10 10:03:32.658298 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:32.657813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.30166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/tigera-uisettingsgroup-getter\" ","response":"range_response_count:0 size:5"} May 10 10:03:32.658298 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:32.657835Z","caller":"traceutil/trace.go:171","msg":"trace[1895110725] range","detail":"{range_begin:/registry/clusterrolebindings/tigera-uisettingsgroup-getter; range_end:; response_count:0; response_revision:659; }","duration":"121.381501ms","start":"2025-05-10T10:03:32.536448Z","end":"2025-05-10T10:03:32.657830Z","steps":["trace[1895110725] 'agreement among raft nodes before linearized reading' (duration: 121.311349ms)"],"step_count":1} May 10 10:03:32.716790 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:32.716354Z","caller":"traceutil/trace.go:171","msg":"trace[340349128] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"150.429417ms","start":"2025-05-10T10:03:32.565907Z","end":"2025-05-10T10:03:32.716336Z","steps":["trace[340349128] 'process raft request' (duration: 150.336943ms)"],"step_count":1} May 10 10:03:32.739626 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:32.739320Z","caller":"traceutil/trace.go:171","msg":"trace[761580041] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"104.820189ms","start":"2025-05-10T10:03:32.634472Z","end":"2025-05-10T10:03:32.739292Z","steps":["trace[761580041] 'process raft request' (duration: 104.650929ms)"],"step_count":1} May 10 10:03:35.483984 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:35.483303Z","caller":"traceutil/trace.go:171","msg":"trace[82245295] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"108.419424ms","start":"2025-05-10T10:03:35.374806Z","end":"2025-05-10T10:03:35.483225Z","steps":["trace[82245295] 'process raft request' (duration: 90.354649ms)","trace[82245295] 'compare' (duration: 17.892689ms)"],"step_count":2} May 10 10:03:35.664306 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:35.663669Z","caller":"traceutil/trace.go:171","msg":"trace[1113197230] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"140.862413ms","start":"2025-05-10T10:03:35.522779Z","end":"2025-05-10T10:03:35.663641Z","steps":["trace[1113197230] 'read index received' (duration: 9.841654ms)","trace[1113197230] 'applied index is now lower than readState.Index' (duration: 131.020178ms)"],"step_count":2} May 10 10:03:35.664306 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:35.663755Z","caller":"traceutil/trace.go:171","msg":"trace[662198911] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"142.469764ms","start":"2025-05-10T10:03:35.521266Z","end":"2025-05-10T10:03:35.663736Z","steps":["trace[662198911] 'process raft request' (duration: 69.615895ms)","trace[662198911] 'compare' (duration: 72.651545ms)"],"step_count":2} May 10 10:03:35.664306 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:35.663910Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.086617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" ","response":"range_response_count:1 size:3434"} May 10 10:03:35.664306 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:35.663963Z","caller":"traceutil/trace.go:171","msg":"trace[733085736] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:673; }","duration":"141.21999ms","start":"2025-05-10T10:03:35.522727Z","end":"2025-05-10T10:03:35.663947Z","steps":["trace[733085736] 'agreement among raft nodes before linearized reading' (duration: 141.023659ms)"],"step_count":1} May 10 10:03:38.099315 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:38.098894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.005324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 10 10:03:38.099315 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:38.098990Z","caller":"traceutil/trace.go:171","msg":"trace[1557500362] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"166.425012ms","start":"2025-05-10T10:03:37.932553Z","end":"2025-05-10T10:03:38.098978Z","steps":["trace[1557500362] 'process raft request' (duration: 40.235548ms)","trace[1557500362] 'compare' (duration: 125.882512ms)"],"step_count":2} May 10 10:03:38.458202 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:38.457787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.03248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/10.0.0.118.183e224c9cffccd4\" ","response":"range_response_count:0 size:5"} May 10 10:03:38.458202 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:38.457851Z","caller":"traceutil/trace.go:171","msg":"trace[946555043] range","detail":"{range_begin:/registry/events/default/10.0.0.118.183e224c9cffccd4; range_end:; response_count:0; response_revision:687; }","duration":"251.144151ms","start":"2025-05-10T10:03:38.206696Z","end":"2025-05-10T10:03:38.457840Z","steps":["trace[946555043] 'range keys from in-memory index tree' (duration: 250.977756ms)"],"step_count":1} May 10 10:03:38.458202 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:38.457856Z","caller":"traceutil/trace.go:171","msg":"trace[1579630673] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"250.481506ms","start":"2025-05-10T10:03:38.207351Z","end":"2025-05-10T10:03:38.457833Z","steps":["trace[1579630673] 'process raft request' (duration: 206.285678ms)","trace[1579630673] 'compare' (duration: 44.092121ms)"],"step_count":2} May 10 10:03:38.665510 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:38.665123Z","caller":"traceutil/trace.go:171","msg":"trace[1622946474] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"120.45186ms","start":"2025-05-10T10:03:38.544654Z","end":"2025-05-10T10:03:38.665106Z","steps":["trace[1622946474] 'process raft request' (duration: 101.767821ms)","trace[1622946474] 'compare' (duration: 18.561147ms)"],"step_count":2} May 10 10:03:38.665510 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:38.665222Z","caller":"traceutil/trace.go:171","msg":"trace[2126947506] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"118.779323ms","start":"2025-05-10T10:03:38.546439Z","end":"2025-05-10T10:03:38.665218Z","steps":["trace[2126947506] 'process raft request' (duration: 118.63532ms)"],"step_count":1} May 10 10:03:38.855381 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:38.854775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.244514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.118\" ","response":"","error":"context canceled"} May 10 10:03:38.855381 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:38.854828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.111651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/10.0.0.118.183e224c9cffdb1d\" ","response":"","error":"context canceled"} May 10 10:03:38.855381 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:38.854915Z","caller":"traceutil/trace.go:171","msg":"trace[475226766] range","detail":"{range_begin:/registry/events/default/10.0.0.118.183e224c9cffdb1d; range_end:; }","duration":"164.254741ms","start":"2025-05-10T10:03:38.690640Z","end":"2025-05-10T10:03:38.854894Z","steps":["trace[475226766] 'agreement among raft nodes before linearized reading' (duration: 164.138371ms)"],"step_count":1} May 10 10:03:38.855381 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:38.854857Z","caller":"traceutil/trace.go:171","msg":"trace[327755228] range","detail":"{range_begin:/registry/minions/10.0.0.118; range_end:; }","duration":"156.376254ms","start":"2025-05-10T10:03:38.698463Z","end":"2025-05-10T10:03:38.854840Z","steps":["trace[327755228] 'agreement among raft nodes before linearized reading' (duration: 156.272748ms)"],"step_count":1} May 10 10:03:39.003164 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.002740Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.033403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 10 10:03:39.003671 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.002897Z","caller":"traceutil/trace.go:171","msg":"trace[434506764] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"451.754643ms","start":"2025-05-10T10:03:38.551125Z","end":"2025-05-10T10:03:39.002880Z","steps":["trace[434506764] 'process raft request' (duration: 335.482858ms)","trace[434506764] 'compare' (duration: 115.893419ms)"],"step_count":2} May 10 10:03:39.003671 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003001Z","caller":"traceutil/trace.go:171","msg":"trace[971163680] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"450.377025ms","start":"2025-05-10T10:03:38.552609Z","end":"2025-05-10T10:03:39.002986Z","steps":["trace[971163680] 'process raft request' (duration: 450.213976ms)"],"step_count":1} May 10 10:03:39.003671 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.002992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:38.551114Z","time spent":"451.817582ms","remote":"10.0.0.112:40508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11208,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:39.003671 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.003051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:38.552601Z","time spent":"450.427221ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3358,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 10 10:03:39.003847 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003076Z","caller":"traceutil/trace.go:171","msg":"trace[1139522435] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:714; }","duration":"312.331039ms","start":"2025-05-10T10:03:38.690725Z","end":"2025-05-10T10:03:39.003056Z","steps":["trace[1139522435] 'read index received' (duration: 195.875016ms)","trace[1139522435] 'applied index is now lower than readState.Index' (duration: 116.455352ms)"],"step_count":2} May 10 10:03:39.003847 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003096Z","caller":"traceutil/trace.go:171","msg":"trace[1927395283] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"405.632359ms","start":"2025-05-10T10:03:38.597457Z","end":"2025-05-10T10:03:39.003089Z","steps":["trace[1927395283] 'process raft request' (duration: 405.456226ms)"],"step_count":1} May 10 10:03:39.003847 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.003134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:38.597430Z","time spent":"405.681903ms","remote":"10.0.0.112:40156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2248,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:39.003847 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003137Z","caller":"traceutil/trace.go:171","msg":"trace[1581290991] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"333.670598ms","start":"2025-05-10T10:03:38.669460Z","end":"2025-05-10T10:03:39.003131Z","steps":["trace[1581290991] 'process raft request' (duration: 333.50276ms)"],"step_count":1} May 10 10:03:39.003954 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003176Z","caller":"traceutil/trace.go:171","msg":"trace[1324479193] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"450.453401ms","start":"2025-05-10T10:03:38.552717Z","end":"2025-05-10T10:03:39.003170Z","steps":["trace[1324479193] 'process raft request' (duration: 450.158742ms)"],"step_count":1} May 10 10:03:39.003954 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.003215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:38.552696Z","time spent":"450.498906ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13758,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:39.003954 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.003176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:38.669437Z","time spent":"333.714881ms","remote":"10.0.0.112:40054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":701,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 10 10:03:39.003954 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003284Z","caller":"traceutil/trace.go:171","msg":"trace[671675452] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"216.843293ms","start":"2025-05-10T10:03:38.786435Z","end":"2025-05-10T10:03:39.003279Z","steps":["trace[671675452] 'process raft request' (duration: 216.554355ms)"],"step_count":1} May 10 10:03:39.004081 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.003290Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.296312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.118\" ","response":"range_response_count:1 size:2263"} May 10 10:03:39.004081 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003313Z","caller":"traceutil/trace.go:171","msg":"trace[698424408] range","detail":"{range_begin:/registry/minions/10.0.0.118; range_end:; response_count:1; response_revision:700; }","duration":"144.319626ms","start":"2025-05-10T10:03:38.858985Z","end":"2025-05-10T10:03:39.003305Z","steps":["trace[698424408] 'agreement among raft nodes before linearized reading' (duration: 144.283958ms)"],"step_count":1} May 10 10:03:39.004081 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.003333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.354875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/10.0.0.118\" ","response":"range_response_count:0 size:5"} May 10 10:03:39.004081 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.003350Z","caller":"traceutil/trace.go:171","msg":"trace[1057670595] range","detail":"{range_begin:/registry/csinodes/10.0.0.118; range_end:; response_count:0; response_revision:700; }","duration":"100.394271ms","start":"2025-05-10T10:03:38.902950Z","end":"2025-05-10T10:03:39.003344Z","steps":["trace[1057670595] 'agreement among raft nodes before linearized reading' (duration: 100.366628ms)"],"step_count":1} May 10 10:03:39.369957 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369115Z","caller":"traceutil/trace.go:171","msg":"trace[734171935] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"359.546606ms","start":"2025-05-10T10:03:39.009536Z","end":"2025-05-10T10:03:39.369083Z","steps":["trace[734171935] 'process raft request' (duration: 282.207508ms)","trace[734171935] 'compare' (duration: 77.137387ms)"],"step_count":2} May 10 10:03:39.369957 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.369267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:39.009528Z","time spent":"359.66471ms","remote":"10.0.0.112:40156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:39.369957 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369277Z","caller":"traceutil/trace.go:171","msg":"trace[966907014] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"357.890389ms","start":"2025-05-10T10:03:39.011373Z","end":"2025-05-10T10:03:39.369263Z","steps":["trace[966907014] 'process raft request' (duration: 357.804205ms)"],"step_count":1} May 10 10:03:39.369957 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.369331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:39.011364Z","time spent":"357.940243ms","remote":"10.0.0.112:40054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 10 10:03:39.370614 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369346Z","caller":"traceutil/trace.go:171","msg":"trace[275994098] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:721; }","duration":"298.608425ms","start":"2025-05-10T10:03:39.070720Z","end":"2025-05-10T10:03:39.369329Z","steps":["trace[275994098] 'read index received' (duration: 221.034582ms)","trace[275994098] 'applied index is now lower than readState.Index' (duration: 77.573342ms)"],"step_count":2} May 10 10:03:39.370614 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369385Z","caller":"traceutil/trace.go:171","msg":"trace[1287815446] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"359.408885ms","start":"2025-05-10T10:03:39.009955Z","end":"2025-05-10T10:03:39.369363Z","steps":["trace[1287815446] 'process raft request' (duration: 359.066637ms)"],"step_count":1} May 10 10:03:39.370614 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369423Z","caller":"traceutil/trace.go:171","msg":"trace[116987003] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"356.670558ms","start":"2025-05-10T10:03:39.012742Z","end":"2025-05-10T10:03:39.369412Z","steps":["trace[116987003] 'process raft request' (duration: 356.514682ms)"],"step_count":1} May 10 10:03:39.370614 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.369441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:39.009945Z","time spent":"359.474449ms","remote":"10.0.0.112:40424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":657,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 10 10:03:39.370783 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.369457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.724765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.118\" ","response":"range_response_count:1 size:2531"} May 10 10:03:39.370783 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.369464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:39.012721Z","time spent":"356.720302ms","remote":"10.0.0.112:40508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2906,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:39.370783 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369516Z","caller":"traceutil/trace.go:171","msg":"trace[138320530] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"300.921356ms","start":"2025-05-10T10:03:39.068585Z","end":"2025-05-10T10:03:39.369506Z","steps":["trace[138320530] 'process raft request' (duration: 300.724173ms)"],"step_count":1} May 10 10:03:39.370783 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369531Z","caller":"traceutil/trace.go:171","msg":"trace[2103359057] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"357.17312ms","start":"2025-05-10T10:03:39.012350Z","end":"2025-05-10T10:03:39.369523Z","steps":["trace[2103359057] 'process raft request' (duration: 356.877098ms)"],"step_count":1} May 10 10:03:39.370889 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.369486Z","caller":"traceutil/trace.go:171","msg":"trace[1843248497] range","detail":"{range_begin:/registry/minions/10.0.0.118; range_end:; response_count:1; response_revision:707; }","duration":"298.809015ms","start":"2025-05-10T10:03:39.070669Z","end":"2025-05-10T10:03:39.369478Z","steps":["trace[1843248497] 'agreement among raft nodes before linearized reading' (duration: 298.741928ms)"],"step_count":1} May 10 10:03:39.370889 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.369556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:39.068567Z","time spent":"300.963115ms","remote":"10.0.0.112:40054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 10 10:03:39.370889 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.369571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:39.012342Z","time spent":"357.208597ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 10 10:03:39.628153 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.627591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.538047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 10 10:03:39.628153 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.627717Z","caller":"traceutil/trace.go:171","msg":"trace[537523052] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"254.272852ms","start":"2025-05-10T10:03:39.373430Z","end":"2025-05-10T10:03:39.627703Z","steps":["trace[537523052] 'process raft request' (duration: 107.552119ms)","trace[537523052] 'compare' (duration: 146.41299ms)"],"step_count":2} May 10 10:03:39.638039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.637502Z","caller":"traceutil/trace.go:171","msg":"trace[1279826725] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"263.169214ms","start":"2025-05-10T10:03:39.374309Z","end":"2025-05-10T10:03:39.637478Z","steps":["trace[1279826725] 'process raft request' (duration: 263.073683ms)"],"step_count":1} May 10 10:03:39.638039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.637610Z","caller":"traceutil/trace.go:171","msg":"trace[1860860216] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"263.479221ms","start":"2025-05-10T10:03:39.374115Z","end":"2025-05-10T10:03:39.637595Z","steps":["trace[1860860216] 'process raft request' (duration: 263.123577ms)"],"step_count":1} May 10 10:03:39.638039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.637649Z","caller":"traceutil/trace.go:171","msg":"trace[829508212] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"258.877665ms","start":"2025-05-10T10:03:39.378762Z","end":"2025-05-10T10:03:39.637640Z","steps":["trace[829508212] 'process raft request' (duration: 258.740334ms)"],"step_count":1} May 10 10:03:39.638039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.637709Z","caller":"traceutil/trace.go:171","msg":"trace[1601622552] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"176.247744ms","start":"2025-05-10T10:03:39.461452Z","end":"2025-05-10T10:03:39.637700Z","steps":["trace[1601622552] 'process raft request' (duration: 176.093953ms)"],"step_count":1} May 10 10:03:39.638039 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.637723Z","caller":"traceutil/trace.go:171","msg":"trace[1728011733] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"261.596405ms","start":"2025-05-10T10:03:39.376120Z","end":"2025-05-10T10:03:39.637716Z","steps":["trace[1728011733] 'process raft request' (duration: 261.295335ms)"],"step_count":1} May 10 10:03:39.638283 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.637593Z","caller":"traceutil/trace.go:171","msg":"trace[853139014] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:727; }","duration":"253.764831ms","start":"2025-05-10T10:03:39.383805Z","end":"2025-05-10T10:03:39.637570Z","steps":["trace[853139014] 'read index received' (duration: 97.258882ms)","trace[853139014] 'applied index is now lower than readState.Index' (duration: 156.505077ms)"],"step_count":2} May 10 10:03:39.638283 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.637713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.872613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} May 10 10:03:39.638283 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.637763Z","caller":"traceutil/trace.go:171","msg":"trace[2061760721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"253.97638ms","start":"2025-05-10T10:03:39.383778Z","end":"2025-05-10T10:03:39.637755Z","steps":["trace[2061760721] 'agreement among raft nodes before linearized reading' (duration: 253.870299ms)"],"step_count":1} May 10 10:03:39.645186 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:39.644942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.375551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/calico-system/csi-node-driver-s9mtc.183e2249ecbe2183\" ","response":"range_response_count:1 size:868"} May 10 10:03:39.645186 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:39.644982Z","caller":"traceutil/trace.go:171","msg":"trace[2007684752] range","detail":"{range_begin:/registry/events/calico-system/csi-node-driver-s9mtc.183e2249ecbe2183; range_end:; response_count:1; response_revision:714; }","duration":"217.441636ms","start":"2025-05-10T10:03:39.427529Z","end":"2025-05-10T10:03:39.644971Z","steps":["trace[2007684752] 'agreement among raft nodes before linearized reading' (duration: 217.326026ms)"],"step_count":1} May 10 10:03:40.368643 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.368233Z","caller":"traceutil/trace.go:171","msg":"trace[2078428802] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"266.717356ms","start":"2025-05-10T10:03:40.101499Z","end":"2025-05-10T10:03:40.368216Z","steps":["trace[2078428802] 'process raft request' (duration: 195.800404ms)","trace[2078428802] 'compare' (duration: 70.748403ms)"],"step_count":2} May 10 10:03:40.368866 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.368390Z","caller":"traceutil/trace.go:171","msg":"trace[329474567] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"210.136068ms","start":"2025-05-10T10:03:40.158224Z","end":"2025-05-10T10:03:40.368360Z","steps":["trace[329474567] 'process raft request' (duration: 209.962219ms)"],"step_count":1} May 10 10:03:40.875840 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.875371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.446634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 10 10:03:40.875840 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.875533Z","caller":"traceutil/trace.go:171","msg":"trace[1850078921] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"445.489583ms","start":"2025-05-10T10:03:40.430032Z","end":"2025-05-10T10:03:40.875521Z","steps":["trace[1850078921] 'process raft request' (duration: 445.42479ms)"],"step_count":1} May 10 10:03:40.877068 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.875564Z","caller":"traceutil/trace.go:171","msg":"trace[2076735167] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"446.191313ms","start":"2025-05-10T10:03:40.429356Z","end":"2025-05-10T10:03:40.875548Z","steps":["trace[2076735167] 'process raft request' (duration: 79.513691ms)","trace[2076735167] 'compare' (duration: 366.344491ms)"],"step_count":2} May 10 10:03:40.877068 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.875615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:40.430020Z","time spent":"445.565477ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4764,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:40.877068 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.875632Z","caller":"traceutil/trace.go:171","msg":"trace[137586058] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"444.87025ms","start":"2025-05-10T10:03:40.430753Z","end":"2025-05-10T10:03:40.875624Z","steps":["trace[137586058] 'process raft request' (duration: 444.74345ms)"],"step_count":1} May 10 10:03:40.877188 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.875648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:40.429334Z","time spent":"446.278337ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:40.877188 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.875701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:40.430745Z","time spent":"444.911528ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4138,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:40.877188 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.875794Z","caller":"traceutil/trace.go:171","msg":"trace[268233357] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:745; }","duration":"409.405827ms","start":"2025-05-10T10:03:40.466378Z","end":"2025-05-10T10:03:40.875783Z","steps":["trace[268233357] 'read index received' (duration: 42.541912ms)","trace[268233357] 'applied index is now lower than readState.Index' (duration: 366.863124ms)"],"step_count":2} May 10 10:03:40.877291 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.875873Z","caller":"traceutil/trace.go:171","msg":"trace[1590857660] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"444.489649ms","start":"2025-05-10T10:03:40.431370Z","end":"2025-05-10T10:03:40.875860Z","steps":["trace[1590857660] 'process raft request' (duration: 444.175844ms)"],"step_count":1} May 10 10:03:40.877291 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.875965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:40.431362Z","time spent":"444.579769ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4138,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} May 10 10:03:40.877291 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.875965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.588122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-m22ld\" ","response":"range_response_count:1 size:3814"} May 10 10:03:40.877291 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.875995Z","caller":"traceutil/trace.go:171","msg":"trace[527988488] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7db6d8ff4d-m22ld; range_end:; response_count:1; response_revision:730; }","duration":"409.654598ms","start":"2025-05-10T10:03:40.466335Z","end":"2025-05-10T10:03:40.875990Z","steps":["trace[527988488] 'agreement among raft nodes before linearized reading' (duration: 409.548898ms)"],"step_count":1} May 10 10:03:40.877411 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:40.875995Z","caller":"traceutil/trace.go:171","msg":"trace[46909842] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"409.408733ms","start":"2025-05-10T10:03:40.466580Z","end":"2025-05-10T10:03:40.875988Z","steps":["trace[46909842] 'process raft request' (duration: 409.172835ms)"],"step_count":1} May 10 10:03:40.877411 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.876012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:40.466317Z","time spent":"409.690946ms","remote":"10.0.0.112:40170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3838,"request content":"key:\"/registry/pods/kube-system/coredns-7db6d8ff4d-m22ld\" "} May 10 10:03:40.877411 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:40.876029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-05-10T10:03:40.466568Z","time spent":"409.44412ms","remote":"10.0.0.112:40054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} May 10 10:03:45.927036 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:45.926587Z","caller":"traceutil/trace.go:171","msg":"trace[1628377676] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"167.999772ms","start":"2025-05-10T10:03:45.758568Z","end":"2025-05-10T10:03:45.926568Z","steps":["trace[1628377676] 'process raft request' (duration: 167.858864ms)"],"step_count":1} May 10 10:03:47.861540 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:47.861143Z","caller":"traceutil/trace.go:171","msg":"trace[173090071] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"101.025897ms","start":"2025-05-10T10:03:47.760097Z","end":"2025-05-10T10:03:47.861122Z","steps":["trace[173090071] 'process raft request' (duration: 48.743119ms)","trace[173090071] 'compare' (duration: 52.175405ms)"],"step_count":2} May 10 10:03:52.395944 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:52.395594Z","caller":"traceutil/trace.go:171","msg":"trace[1522133503] transaction","detail":"{read_only:false; number_of_response:0; response_revision:808; }","duration":"127.732982ms","start":"2025-05-10T10:03:52.267839Z","end":"2025-05-10T10:03:52.395572Z","steps":["trace[1522133503] 'process raft request' (duration: 44.251827ms)","trace[1522133503] 'compare' (duration: 83.427903ms)"],"step_count":2} May 10 10:03:52.639453 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:52.638857Z","caller":"traceutil/trace.go:171","msg":"trace[1662279430] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:833; }","duration":"208.998292ms","start":"2025-05-10T10:03:52.429836Z","end":"2025-05-10T10:03:52.638834Z","steps":["trace[1662279430] 'read index received' (duration: 14.731243ms)","trace[1662279430] 'applied index is now lower than readState.Index' (duration: 194.266148ms)"],"step_count":2} May 10 10:03:52.639453 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:52.638927Z","caller":"traceutil/trace.go:171","msg":"trace[2041756572] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"212.298979ms","start":"2025-05-10T10:03:52.426591Z","end":"2025-05-10T10:03:52.638890Z","steps":["trace[2041756572] 'process raft request' (duration: 168.742533ms)","trace[2041756572] 'compare' (duration: 43.370091ms)"],"step_count":2} May 10 10:03:52.639453 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:52.639109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.248839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-7db6d8ff4d-m22ld.183e224d60a1d4a4\" ","response":"range_response_count:1 size:824"} May 10 10:03:52.639453 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:52.639168Z","caller":"traceutil/trace.go:171","msg":"trace[34108233] range","detail":"{range_begin:/registry/events/kube-system/coredns-7db6d8ff4d-m22ld.183e224d60a1d4a4; range_end:; response_count:1; response_revision:810; }","duration":"209.337156ms","start":"2025-05-10T10:03:52.429822Z","end":"2025-05-10T10:03:52.639159Z","steps":["trace[34108233] 'agreement among raft nodes before linearized reading' (duration: 209.094866ms)"],"step_count":1} May 10 10:03:54.798853 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:54.798339Z","caller":"traceutil/trace.go:171","msg":"trace[2021914904] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"176.460606ms","start":"2025-05-10T10:03:54.621859Z","end":"2025-05-10T10:03:54.798320Z","steps":["trace[2021914904] 'process raft request' (duration: 176.305019ms)"],"step_count":1} May 10 10:03:54.798853 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:54.798429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.357999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/calico-system/calico-typha\" ","response":"range_response_count:1 size:660"} May 10 10:03:54.798853 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:54.798458Z","caller":"traceutil/trace.go:171","msg":"trace[887531794] range","detail":"{range_begin:/registry/services/endpoints/calico-system/calico-typha; range_end:; response_count:1; response_revision:857; }","duration":"151.431399ms","start":"2025-05-10T10:03:54.647020Z","end":"2025-05-10T10:03:54.798451Z","steps":["trace[887531794] 'agreement among raft nodes before linearized reading' (duration: 151.304969ms)"],"step_count":1} May 10 10:03:54.798853 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:54.798482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.809849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-system/calico-cni-plugin\" ","response":"range_response_count:1 size:537"} May 10 10:03:54.798853 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:54.798489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.869838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" ","response":"range_response_count:1 size:5046"} May 10 10:03:54.799558 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:54.798500Z","caller":"traceutil/trace.go:171","msg":"trace[83287555] range","detail":"{range_begin:/registry/serviceaccounts/calico-system/calico-cni-plugin; range_end:; response_count:1; response_revision:857; }","duration":"139.852791ms","start":"2025-05-10T10:03:54.658641Z","end":"2025-05-10T10:03:54.798494Z","steps":["trace[83287555] 'agreement among raft nodes before linearized reading' (duration: 139.794781ms)"],"step_count":1} May 10 10:03:54.799558 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:54.798521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.447322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/calico-system/calico-typha\" ","response":"range_response_count:1 size:660"} May 10 10:03:54.799558 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:54.798518Z","caller":"traceutil/trace.go:171","msg":"trace[110060802] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:857; }","duration":"170.915626ms","start":"2025-05-10T10:03:54.627592Z","end":"2025-05-10T10:03:54.798508Z","steps":["trace[110060802] 'agreement among raft nodes before linearized reading' (duration: 170.847016ms)"],"step_count":1} May 10 10:03:54.799558 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:54.798533Z","caller":"traceutil/trace.go:171","msg":"trace[1119878897] range","detail":"{range_begin:/registry/services/endpoints/calico-system/calico-typha; range_end:; response_count:1; response_revision:857; }","duration":"140.486897ms","start":"2025-05-10T10:03:54.658043Z","end":"2025-05-10T10:03:54.798530Z","steps":["trace[1119878897] 'agreement among raft nodes before linearized reading' (duration: 140.45157ms)"],"step_count":1} May 10 10:03:55.351184 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:55.350803Z","caller":"traceutil/trace.go:171","msg":"trace[197522544] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"111.017809ms","start":"2025-05-10T10:03:55.239765Z","end":"2025-05-10T10:03:55.350783Z","steps":["trace[197522544] 'process raft request' (duration: 22.500606ms)","trace[197522544] 'compare' (duration: 88.375133ms)"],"step_count":2} May 10 10:03:55.362275 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:55.361881Z","caller":"traceutil/trace.go:171","msg":"trace[1856972144] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"120.821004ms","start":"2025-05-10T10:03:55.241041Z","end":"2025-05-10T10:03:55.361862Z","steps":["trace[1856972144] 'process raft request' (duration: 120.719181ms)"],"step_count":1} May 10 10:03:56.936971 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:56.936109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.095665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} May 10 10:03:56.936971 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:56.936204Z","caller":"traceutil/trace.go:171","msg":"trace[1811447047] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"145.490307ms","start":"2025-05-10T10:03:56.790701Z","end":"2025-05-10T10:03:56.936192Z","steps":["trace[1811447047] 'read index received' (duration: 37.162771ms)","trace[1811447047] 'applied index is now lower than readState.Index' (duration: 108.326914ms)"],"step_count":2} May 10 10:03:56.937551 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:03:56.936289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.591319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/calico-apiserver\" ","response":"range_response_count:1 size:773"} May 10 10:03:56.937551 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:56.936276Z","caller":"traceutil/trace.go:171","msg":"trace[1943141302] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"153.541129ms","start":"2025-05-10T10:03:56.782696Z","end":"2025-05-10T10:03:56.936237Z","steps":["trace[1943141302] 'process raft request' (duration: 45.245684ms)","trace[1943141302] 'compare' (duration: 107.960568ms)"],"step_count":2} May 10 10:03:56.937551 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:56.936309Z","caller":"traceutil/trace.go:171","msg":"trace[1572568977] range","detail":"{range_begin:/registry/namespaces/calico-apiserver; range_end:; response_count:1; response_revision:902; }","duration":"145.650672ms","start":"2025-05-10T10:03:56.790653Z","end":"2025-05-10T10:03:56.936303Z","steps":["trace[1572568977] 'agreement among raft nodes before linearized reading' (duration: 145.569397ms)"],"step_count":1} May 10 10:03:59.463506 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:03:59.463123Z","caller":"traceutil/trace.go:171","msg":"trace[1289459730] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"127.943176ms","start":"2025-05-10T10:03:59.335160Z","end":"2025-05-10T10:03:59.463103Z","steps":["trace[1289459730] 'process raft request' (duration: 95.761841ms)","trace[1289459730] 'compare' (duration: 32.097145ms)"],"step_count":2} May 10 10:04:04.847382 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:04.846935Z","caller":"traceutil/trace.go:171","msg":"trace[1476164942] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"165.185479ms","start":"2025-05-10T10:04:04.681720Z","end":"2025-05-10T10:04:04.846906Z","steps":["trace[1476164942] 'process raft request' (duration: 165.002531ms)"],"step_count":1} May 10 10:04:06.583203 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:06.582744Z","caller":"traceutil/trace.go:171","msg":"trace[1957355921] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"102.914988ms","start":"2025-05-10T10:04:06.479810Z","end":"2025-05-10T10:04:06.582725Z","steps":["trace[1957355921] 'read index received' (duration: 33.819112ms)","trace[1957355921] 'applied index is now lower than readState.Index' (duration: 69.095335ms)"],"step_count":2} May 10 10:04:06.583203 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:06.582826Z","caller":"traceutil/trace.go:171","msg":"trace[382809837] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"103.251159ms","start":"2025-05-10T10:04:06.479567Z","end":"2025-05-10T10:04:06.582819Z","steps":["trace[382809837] 'process raft request' (duration: 33.984608ms)","trace[382809837] 'compare' (duration: 69.061621ms)"],"step_count":2} May 10 10:04:06.583855 etcd-wrapper[1769]: {"level":"warn","ts":"2025-05-10T10:04:06.582918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.093349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/10.0.0.118\" ","response":"range_response_count:1 size:3155"} May 10 10:04:06.583855 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:06.582949Z","caller":"traceutil/trace.go:171","msg":"trace[1516336142] range","detail":"{range_begin:/registry/minions/10.0.0.118; range_end:; response_count:1; response_revision:973; }","duration":"103.155025ms","start":"2025-05-10T10:04:06.479786Z","end":"2025-05-10T10:04:06.582941Z","steps":["trace[1516336142] 'agreement among raft nodes before linearized reading' (duration: 103.044634ms)"],"step_count":1} May 10 10:04:06.587484 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:06.587120Z","caller":"traceutil/trace.go:171","msg":"trace[855819740] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"104.779223ms","start":"2025-05-10T10:04:06.482331Z","end":"2025-05-10T10:04:06.587110Z","steps":["trace[855819740] 'process raft request' (duration: 104.677759ms)"],"step_count":1} May 10 10:04:06.587484 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:06.587200Z","caller":"traceutil/trace.go:171","msg":"trace[1134551419] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"101.039763ms","start":"2025-05-10T10:04:06.486150Z","end":"2025-05-10T10:04:06.587190Z","steps":["trace[1134551419] 'process raft request' (duration: 100.904626ms)"],"step_count":1} May 10 10:04:06.587484 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:06.587233Z","caller":"traceutil/trace.go:171","msg":"trace[957315861] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"105.960786ms","start":"2025-05-10T10:04:06.481183Z","end":"2025-05-10T10:04:06.587143Z","steps":["trace[957315861] 'process raft request' (duration: 105.759022ms)"],"step_count":1} May 10 10:04:11.068840 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:11.068416Z","caller":"traceutil/trace.go:171","msg":"trace[1596564854] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"119.367195ms","start":"2025-05-10T10:04:10.949024Z","end":"2025-05-10T10:04:11.068391Z","steps":["trace[1596564854] 'process raft request' (duration: 87.393087ms)","trace[1596564854] 'compare' (duration: 31.799395ms)"],"step_count":2} May 10 10:04:11.068840 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:11.068425Z","caller":"traceutil/trace.go:171","msg":"trace[973185468] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"117.918832ms","start":"2025-05-10T10:04:10.950486Z","end":"2025-05-10T10:04:11.068405Z","steps":["trace[973185468] 'process raft request' (duration: 117.885619ms)"],"step_count":1} May 10 10:04:11.068840 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:11.068445Z","caller":"traceutil/trace.go:171","msg":"trace[1262672823] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"119.300609ms","start":"2025-05-10T10:04:10.949133Z","end":"2025-05-10T10:04:11.068433Z","steps":["trace[1262672823] 'process raft request' (duration: 119.199136ms)"],"step_count":1} May 10 10:04:13.029960 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:13.029553Z","caller":"traceutil/trace.go:171","msg":"trace[1901933502] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"143.376671ms","start":"2025-05-10T10:04:12.886155Z","end":"2025-05-10T10:04:13.029532Z","steps":["trace[1901933502] 'process raft request' (duration: 84.17461ms)","trace[1901933502] 'compare' (duration: 59.101569ms)"],"step_count":2} May 10 10:04:33.193807 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:33.193327Z","caller":"traceutil/trace.go:171","msg":"trace[486450065] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"157.445294ms","start":"2025-05-10T10:04:33.035865Z","end":"2025-05-10T10:04:33.193311Z","steps":["trace[486450065] 'process raft request' (duration: 157.415998ms)"],"step_count":1} May 10 10:04:33.193807 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:33.193398Z","caller":"traceutil/trace.go:171","msg":"trace[56844675] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"162.489178ms","start":"2025-05-10T10:04:33.030879Z","end":"2025-05-10T10:04:33.193369Z","steps":["trace[56844675] 'process raft request' (duration: 162.38053ms)"],"step_count":1} May 10 10:04:33.193807 etcd-wrapper[1769]: {"level":"info","ts":"2025-05-10T10:04:33.193408Z","caller":"traceutil/trace.go:171","msg":"trace[1495746858] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"162.574841ms","start":"2025-05-10T10:04:33.030811Z","end":"2025-05-10T10:04:33.193386Z","steps":["trace[1495746858] 'process raft request' (duration: 74.172506ms)","trace[1495746858] 'compare' (duration: 88.173888ms)"],"step_count":2}