Mar 17 17:38:33.892118 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Mon Mar 17 16:07:40 -00 2025 Mar 17 17:38:33.892143 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=d4b838cd9a6f58e8c4a6b615c32b0b28ee0df1660e34033a8fbd0429c6de5fd0 Mar 17 17:38:33.892157 kernel: BIOS-provided physical RAM map: Mar 17 17:38:33.892165 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Mar 17 17:38:33.892173 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Mar 17 17:38:33.892181 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Mar 17 17:38:33.892190 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Mar 17 17:38:33.892199 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Mar 17 17:38:33.892207 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Mar 17 17:38:33.892218 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Mar 17 17:38:33.892226 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 17 17:38:33.892234 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Mar 17 17:38:33.892242 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 17 17:38:33.892250 kernel: NX (Execute Disable) protection: active Mar 17 17:38:33.892261 kernel: APIC: Static calls initialized Mar 17 17:38:33.892272 kernel: SMBIOS 2.8 present. Mar 17 17:38:33.892281 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Mar 17 17:38:33.892290 kernel: Hypervisor detected: KVM Mar 17 17:38:33.892311 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Mar 17 17:38:33.892320 kernel: kvm-clock: using sched offset of 2715505753 cycles Mar 17 17:38:33.892329 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 17 17:38:33.892339 kernel: tsc: Detected 2794.750 MHz processor Mar 17 17:38:33.892348 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 17 17:38:33.892358 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 17 17:38:33.892367 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Mar 17 17:38:33.892380 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Mar 17 17:38:33.892389 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 17 17:38:33.892398 kernel: Using GB pages for direct mapping Mar 17 17:38:33.892407 kernel: ACPI: Early table checksum verification disabled Mar 17 17:38:33.892416 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Mar 17 17:38:33.892426 kernel: ACPI: RSDT 0x000000009CFE2408 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 17:38:33.892435 kernel: ACPI: FACP 0x000000009CFE21E8 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 17:38:33.892444 kernel: ACPI: DSDT 0x000000009CFE0040 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 17:38:33.892455 kernel: ACPI: FACS 0x000000009CFE0000 000040 Mar 17 17:38:33.892465 kernel: ACPI: APIC 0x000000009CFE22DC 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 17:38:33.892474 kernel: ACPI: HPET 0x000000009CFE236C 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 17:38:33.892483 kernel: ACPI: MCFG 0x000000009CFE23A4 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 17:38:33.892492 kernel: ACPI: WAET 0x000000009CFE23E0 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 17:38:33.892501 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21e8-0x9cfe22db] Mar 17 17:38:33.892511 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21e7] Mar 17 17:38:33.892524 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Mar 17 17:38:33.892544 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22dc-0x9cfe236b] Mar 17 17:38:33.892554 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe236c-0x9cfe23a3] Mar 17 17:38:33.892564 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23a4-0x9cfe23df] Mar 17 17:38:33.892573 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23e0-0x9cfe2407] Mar 17 17:38:33.892583 kernel: No NUMA configuration found Mar 17 17:38:33.892592 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Mar 17 17:38:33.892602 kernel: NODE_DATA(0) allocated [mem 0x9cfd6000-0x9cfdbfff] Mar 17 17:38:33.892614 kernel: Zone ranges: Mar 17 17:38:33.892624 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 17 17:38:33.892633 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Mar 17 17:38:33.892643 kernel: Normal empty Mar 17 17:38:33.892652 kernel: Movable zone start for each node Mar 17 17:38:33.892662 kernel: Early memory node ranges Mar 17 17:38:33.892671 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Mar 17 17:38:33.892681 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Mar 17 17:38:33.892691 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Mar 17 17:38:33.892703 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 17 17:38:33.892712 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Mar 17 17:38:33.892722 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Mar 17 17:38:33.892732 kernel: ACPI: PM-Timer IO Port: 0x608 Mar 17 17:38:33.892741 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Mar 17 17:38:33.892751 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Mar 17 17:38:33.892760 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Mar 17 17:38:33.892770 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Mar 17 17:38:33.892779 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 17 17:38:33.892792 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Mar 17 17:38:33.892801 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Mar 17 17:38:33.892811 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 17 17:38:33.892820 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Mar 17 17:38:33.892830 kernel: TSC deadline timer available Mar 17 17:38:33.892839 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Mar 17 17:38:33.892849 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Mar 17 17:38:33.892858 kernel: kvm-guest: KVM setup pv remote TLB flush Mar 17 17:38:33.892868 kernel: kvm-guest: setup PV sched yield Mar 17 17:38:33.892877 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Mar 17 17:38:33.892898 kernel: Booting paravirtualized kernel on KVM Mar 17 17:38:33.892908 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 17 17:38:33.892918 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Mar 17 17:38:33.892936 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 Mar 17 17:38:33.892953 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 Mar 17 17:38:33.892970 kernel: pcpu-alloc: [0] 0 1 2 3 Mar 17 17:38:33.892993 kernel: kvm-guest: PV spinlocks enabled Mar 17 17:38:33.893010 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 17 17:38:33.893034 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=d4b838cd9a6f58e8c4a6b615c32b0b28ee0df1660e34033a8fbd0429c6de5fd0 Mar 17 17:38:33.893049 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 17:38:33.893072 kernel: random: crng init done Mar 17 17:38:33.893083 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 17:38:33.893093 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 17:38:33.893103 kernel: Fallback order for Node 0: 0 Mar 17 17:38:33.893114 kernel: Built 1 zonelists, mobility grouping on. Total pages: 632732 Mar 17 17:38:33.893124 kernel: Policy zone: DMA32 Mar 17 17:38:33.893134 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 17:38:33.893148 kernel: Memory: 2434592K/2571752K available (12288K kernel code, 2303K rwdata, 22744K rodata, 42992K init, 2196K bss, 136900K reserved, 0K cma-reserved) Mar 17 17:38:33.893158 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 17 17:38:33.893169 kernel: ftrace: allocating 37938 entries in 149 pages Mar 17 17:38:33.893179 kernel: ftrace: allocated 149 pages with 4 groups Mar 17 17:38:33.893189 kernel: Dynamic Preempt: voluntary Mar 17 17:38:33.893199 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 17:38:33.893210 kernel: rcu: RCU event tracing is enabled. Mar 17 17:38:33.893220 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 17 17:38:33.893231 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 17:38:33.893244 kernel: Rude variant of Tasks RCU enabled. Mar 17 17:38:33.893254 kernel: Tracing variant of Tasks RCU enabled. Mar 17 17:38:33.893264 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 17:38:33.893274 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 17 17:38:33.893284 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Mar 17 17:38:33.893315 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 17 17:38:33.893326 kernel: Console: colour VGA+ 80x25 Mar 17 17:38:33.893336 kernel: printk: console [ttyS0] enabled Mar 17 17:38:33.893346 kernel: ACPI: Core revision 20230628 Mar 17 17:38:33.893360 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Mar 17 17:38:33.893370 kernel: APIC: Switch to symmetric I/O mode setup Mar 17 17:38:33.893380 kernel: x2apic enabled Mar 17 17:38:33.893390 kernel: APIC: Switched APIC routing to: physical x2apic Mar 17 17:38:33.893400 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Mar 17 17:38:33.893411 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Mar 17 17:38:33.893421 kernel: kvm-guest: setup PV IPIs Mar 17 17:38:33.893443 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Mar 17 17:38:33.893454 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Mar 17 17:38:33.893464 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Mar 17 17:38:33.893475 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Mar 17 17:38:33.893486 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Mar 17 17:38:33.893499 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Mar 17 17:38:33.893510 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 17 17:38:33.893520 kernel: Spectre V2 : Mitigation: Retpolines Mar 17 17:38:33.893539 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 17 17:38:33.893550 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 17 17:38:33.893564 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Mar 17 17:38:33.893574 kernel: RETBleed: Mitigation: untrained return thunk Mar 17 17:38:33.893585 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Mar 17 17:38:33.893596 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Mar 17 17:38:33.893607 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Mar 17 17:38:33.893619 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Mar 17 17:38:33.893629 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Mar 17 17:38:33.893640 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 17 17:38:33.893653 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 17 17:38:33.893664 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 17 17:38:33.893675 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 17 17:38:33.893686 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Mar 17 17:38:33.893697 kernel: Freeing SMP alternatives memory: 32K Mar 17 17:38:33.893707 kernel: pid_max: default: 32768 minimum: 301 Mar 17 17:38:33.893718 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 17 17:38:33.893728 kernel: landlock: Up and running. Mar 17 17:38:33.893739 kernel: SELinux: Initializing. Mar 17 17:38:33.893752 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 17:38:33.893763 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 17:38:33.893774 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Mar 17 17:38:33.893785 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 17 17:38:33.893796 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 17 17:38:33.893806 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 17 17:38:33.893817 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Mar 17 17:38:33.893828 kernel: ... version: 0 Mar 17 17:38:33.893838 kernel: ... bit width: 48 Mar 17 17:38:33.893851 kernel: ... generic registers: 6 Mar 17 17:38:33.893862 kernel: ... value mask: 0000ffffffffffff Mar 17 17:38:33.893872 kernel: ... max period: 00007fffffffffff Mar 17 17:38:33.893883 kernel: ... fixed-purpose events: 0 Mar 17 17:38:33.893893 kernel: ... event mask: 000000000000003f Mar 17 17:38:33.893904 kernel: signal: max sigframe size: 1776 Mar 17 17:38:33.893914 kernel: rcu: Hierarchical SRCU implementation. Mar 17 17:38:33.893925 kernel: rcu: Max phase no-delay instances is 400. Mar 17 17:38:33.893936 kernel: smp: Bringing up secondary CPUs ... Mar 17 17:38:33.893949 kernel: smpboot: x86: Booting SMP configuration: Mar 17 17:38:33.893959 kernel: .... node #0, CPUs: #1 #2 #3 Mar 17 17:38:33.893970 kernel: smp: Brought up 1 node, 4 CPUs Mar 17 17:38:33.893980 kernel: smpboot: Max logical packages: 1 Mar 17 17:38:33.893991 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Mar 17 17:38:33.894002 kernel: devtmpfs: initialized Mar 17 17:38:33.894012 kernel: x86/mm: Memory block size: 128MB Mar 17 17:38:33.894023 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 17:38:33.894034 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 17 17:38:33.894047 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 17:38:33.894058 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 17:38:33.894068 kernel: audit: initializing netlink subsys (disabled) Mar 17 17:38:33.894079 kernel: audit: type=2000 audit(1742233113.562:1): state=initialized audit_enabled=0 res=1 Mar 17 17:38:33.894090 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 17:38:33.894100 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 17 17:38:33.894111 kernel: cpuidle: using governor menu Mar 17 17:38:33.894121 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 17:38:33.894132 kernel: dca service started, version 1.12.1 Mar 17 17:38:33.894145 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Mar 17 17:38:33.894156 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Mar 17 17:38:33.894167 kernel: PCI: Using configuration type 1 for base access Mar 17 17:38:33.894177 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 17 17:38:33.894188 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 17:38:33.894199 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 17 17:38:33.894210 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 17:38:33.894220 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 17 17:38:33.894231 kernel: ACPI: Added _OSI(Module Device) Mar 17 17:38:33.894244 kernel: ACPI: Added _OSI(Processor Device) Mar 17 17:38:33.894255 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 17:38:33.894266 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 17:38:33.894276 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 17:38:33.894287 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 17 17:38:33.894311 kernel: ACPI: Interpreter enabled Mar 17 17:38:33.894323 kernel: ACPI: PM: (supports S0 S3 S5) Mar 17 17:38:33.894336 kernel: ACPI: Using IOAPIC for interrupt routing Mar 17 17:38:33.894347 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 17 17:38:33.894360 kernel: PCI: Using E820 reservations for host bridge windows Mar 17 17:38:33.894371 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Mar 17 17:38:33.894382 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 17 17:38:33.894621 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 17 17:38:33.894851 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Mar 17 17:38:33.895026 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Mar 17 17:38:33.895040 kernel: PCI host bridge to bus 0000:00 Mar 17 17:38:33.895243 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Mar 17 17:38:33.895407 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Mar 17 17:38:33.895562 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Mar 17 17:38:33.895703 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Mar 17 17:38:33.895842 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Mar 17 17:38:33.895980 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Mar 17 17:38:33.896118 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 17 17:38:33.896324 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Mar 17 17:38:33.896503 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Mar 17 17:38:33.896662 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] Mar 17 17:38:33.896881 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xfebd0000-0xfebd0fff] Mar 17 17:38:33.897041 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfebc0000-0xfebcffff pref] Mar 17 17:38:33.897197 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Mar 17 17:38:33.897401 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Mar 17 17:38:33.897578 kernel: pci 0000:00:02.0: reg 0x10: [io 0xc0c0-0xc0df] Mar 17 17:38:33.897732 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xfebd1000-0xfebd1fff] Mar 17 17:38:33.897883 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] Mar 17 17:38:33.898085 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Mar 17 17:38:33.898245 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc000-0xc07f] Mar 17 17:38:33.898450 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfebd2000-0xfebd2fff] Mar 17 17:38:33.898621 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit pref] Mar 17 17:38:33.898804 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Mar 17 17:38:33.898959 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc0e0-0xc0ff] Mar 17 17:38:33.899113 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfebd3000-0xfebd3fff] Mar 17 17:38:33.899267 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit pref] Mar 17 17:38:33.899440 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] Mar 17 17:38:33.899622 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Mar 17 17:38:33.899782 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Mar 17 17:38:33.899957 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Mar 17 17:38:33.900110 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc100-0xc11f] Mar 17 17:38:33.900264 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfebd4000-0xfebd4fff] Mar 17 17:38:33.900452 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Mar 17 17:38:33.900618 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Mar 17 17:38:33.900633 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Mar 17 17:38:33.900650 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Mar 17 17:38:33.900661 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Mar 17 17:38:33.900672 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Mar 17 17:38:33.900683 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Mar 17 17:38:33.900694 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Mar 17 17:38:33.900705 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Mar 17 17:38:33.900716 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Mar 17 17:38:33.900727 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Mar 17 17:38:33.900738 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Mar 17 17:38:33.900752 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Mar 17 17:38:33.900763 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Mar 17 17:38:33.900774 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Mar 17 17:38:33.900785 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Mar 17 17:38:33.900796 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Mar 17 17:38:33.900807 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Mar 17 17:38:33.900818 kernel: iommu: Default domain type: Translated Mar 17 17:38:33.900829 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 17 17:38:33.900839 kernel: PCI: Using ACPI for IRQ routing Mar 17 17:38:33.900853 kernel: PCI: pci_cache_line_size set to 64 bytes Mar 17 17:38:33.900864 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Mar 17 17:38:33.900875 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Mar 17 17:38:33.901029 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Mar 17 17:38:33.901182 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Mar 17 17:38:33.901399 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Mar 17 17:38:33.901415 kernel: vgaarb: loaded Mar 17 17:38:33.901427 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Mar 17 17:38:33.901443 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Mar 17 17:38:33.901454 kernel: clocksource: Switched to clocksource kvm-clock Mar 17 17:38:33.901464 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 17:38:33.901476 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 17:38:33.901486 kernel: pnp: PnP ACPI init Mar 17 17:38:33.901867 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Mar 17 17:38:33.901909 kernel: pnp: PnP ACPI: found 6 devices Mar 17 17:38:33.901918 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 17 17:38:33.901941 kernel: NET: Registered PF_INET protocol family Mar 17 17:38:33.901949 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 17 17:38:33.901958 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 17 17:38:33.901966 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 17:38:33.901974 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 17 17:38:33.901982 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 17 17:38:33.901990 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 17 17:38:33.901998 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 17:38:33.902006 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 17:38:33.902017 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 17:38:33.902025 kernel: NET: Registered PF_XDP protocol family Mar 17 17:38:33.902152 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Mar 17 17:38:33.902265 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Mar 17 17:38:33.902406 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Mar 17 17:38:33.902519 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Mar 17 17:38:33.902641 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Mar 17 17:38:33.902755 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Mar 17 17:38:33.902771 kernel: PCI: CLS 0 bytes, default 64 Mar 17 17:38:33.902779 kernel: Initialise system trusted keyrings Mar 17 17:38:33.902788 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 17 17:38:33.902795 kernel: Key type asymmetric registered Mar 17 17:38:33.902803 kernel: Asymmetric key parser 'x509' registered Mar 17 17:38:33.902811 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 17 17:38:33.902819 kernel: io scheduler mq-deadline registered Mar 17 17:38:33.902827 kernel: io scheduler kyber registered Mar 17 17:38:33.902834 kernel: io scheduler bfq registered Mar 17 17:38:33.902842 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 17 17:38:33.902854 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Mar 17 17:38:33.902862 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Mar 17 17:38:33.902870 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Mar 17 17:38:33.902878 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 17:38:33.902886 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 17 17:38:33.902894 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Mar 17 17:38:33.902902 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Mar 17 17:38:33.902910 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Mar 17 17:38:33.902918 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Mar 17 17:38:33.903060 kernel: rtc_cmos 00:04: RTC can wake from S4 Mar 17 17:38:33.903177 kernel: rtc_cmos 00:04: registered as rtc0 Mar 17 17:38:33.903290 kernel: rtc_cmos 00:04: setting system clock to 2025-03-17T17:38:33 UTC (1742233113) Mar 17 17:38:33.903489 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Mar 17 17:38:33.903499 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Mar 17 17:38:33.903507 kernel: NET: Registered PF_INET6 protocol family Mar 17 17:38:33.903515 kernel: Segment Routing with IPv6 Mar 17 17:38:33.903528 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 17:38:33.903545 kernel: NET: Registered PF_PACKET protocol family Mar 17 17:38:33.903553 kernel: Key type dns_resolver registered Mar 17 17:38:33.903561 kernel: IPI shorthand broadcast: enabled Mar 17 17:38:33.903569 kernel: sched_clock: Marking stable (587004914, 105801548)->(946674965, -253868503) Mar 17 17:38:33.903577 kernel: registered taskstats version 1 Mar 17 17:38:33.903585 kernel: Loading compiled-in X.509 certificates Mar 17 17:38:33.903593 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: 608fb88224bc0ea76afefc598557abb0413f36c0' Mar 17 17:38:33.903601 kernel: Key type .fscrypt registered Mar 17 17:38:33.903609 kernel: Key type fscrypt-provisioning registered Mar 17 17:38:33.903620 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 17:38:33.903628 kernel: ima: Allocated hash algorithm: sha1 Mar 17 17:38:33.903635 kernel: ima: No architecture policies found Mar 17 17:38:33.903643 kernel: clk: Disabling unused clocks Mar 17 17:38:33.903651 kernel: Freeing unused kernel image (initmem) memory: 42992K Mar 17 17:38:33.903658 kernel: Write protecting the kernel read-only data: 36864k Mar 17 17:38:33.903666 kernel: Freeing unused kernel image (rodata/data gap) memory: 1832K Mar 17 17:38:33.903674 kernel: Run /init as init process Mar 17 17:38:33.903684 kernel: with arguments: Mar 17 17:38:33.903693 kernel: /init Mar 17 17:38:33.903700 kernel: with environment: Mar 17 17:38:33.903708 kernel: HOME=/ Mar 17 17:38:33.903715 kernel: TERM=linux Mar 17 17:38:33.903723 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 17:38:33.903734 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Mar 17 17:38:33.903745 systemd[1]: Detected virtualization kvm. Mar 17 17:38:33.903756 systemd[1]: Detected architecture x86-64. Mar 17 17:38:33.903764 systemd[1]: Running in initrd. Mar 17 17:38:33.903773 systemd[1]: No hostname configured, using default hostname. Mar 17 17:38:33.903781 systemd[1]: Hostname set to . Mar 17 17:38:33.903789 systemd[1]: Initializing machine ID from VM UUID. Mar 17 17:38:33.903797 systemd[1]: Queued start job for default target initrd.target. Mar 17 17:38:33.903806 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:38:33.903815 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:38:33.903827 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 17 17:38:33.903850 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:38:33.903861 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 17 17:38:33.903870 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 17 17:38:33.903880 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 17 17:38:33.903891 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 17 17:38:33.903900 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:38:33.903908 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:38:33.903917 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:38:33.903925 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:38:33.903933 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:38:33.903941 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:38:33.903950 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:38:33.903961 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:38:33.903969 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 17 17:38:33.903978 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Mar 17 17:38:33.903986 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:38:33.903995 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:38:33.904003 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:38:33.904012 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:38:33.904020 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 17 17:38:33.904029 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:38:33.904039 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 17 17:38:33.904048 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 17:38:33.904056 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:38:33.904065 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:38:33.904073 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:38:33.904082 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 17 17:38:33.904090 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:38:33.904099 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 17:38:33.904111 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 17 17:38:33.904146 systemd-journald[193]: Collecting audit messages is disabled. Mar 17 17:38:33.904175 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:38:33.904184 systemd-journald[193]: Journal started Mar 17 17:38:33.904205 systemd-journald[193]: Runtime Journal (/run/log/journal/c17ce3911229402898d691c297a5d4f0) is 6.0M, max 48.4M, 42.3M free. Mar 17 17:38:33.895243 systemd-modules-load[194]: Inserted module 'overlay' Mar 17 17:38:33.929982 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 17:38:33.930009 kernel: Bridge firewalling registered Mar 17 17:38:33.930020 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:38:33.922959 systemd-modules-load[194]: Inserted module 'br_netfilter' Mar 17 17:38:33.932137 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:38:33.950627 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:38:33.951568 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:38:33.954322 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:38:33.955327 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:38:33.960156 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:38:33.968080 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:38:33.971458 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:38:33.972827 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:38:33.979469 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:38:33.981864 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:38:33.985498 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 17 17:38:34.003809 dracut-cmdline[231]: dracut-dracut-053 Mar 17 17:38:34.007364 dracut-cmdline[231]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=d4b838cd9a6f58e8c4a6b615c32b0b28ee0df1660e34033a8fbd0429c6de5fd0 Mar 17 17:38:34.016312 systemd-resolved[224]: Positive Trust Anchors: Mar 17 17:38:34.016329 systemd-resolved[224]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:38:34.016369 systemd-resolved[224]: 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 Mar 17 17:38:34.019409 systemd-resolved[224]: Defaulting to hostname 'linux'. Mar 17 17:38:34.020508 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:38:34.026842 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:38:34.095345 kernel: SCSI subsystem initialized Mar 17 17:38:34.104329 kernel: Loading iSCSI transport class v2.0-870. Mar 17 17:38:34.115334 kernel: iscsi: registered transport (tcp) Mar 17 17:38:34.137344 kernel: iscsi: registered transport (qla4xxx) Mar 17 17:38:34.137382 kernel: QLogic iSCSI HBA Driver Mar 17 17:38:34.184278 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 17 17:38:34.193521 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 17 17:38:34.217980 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 17:38:34.218061 kernel: device-mapper: uevent: version 1.0.3 Mar 17 17:38:34.218073 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 17 17:38:34.259339 kernel: raid6: avx2x4 gen() 28838 MB/s Mar 17 17:38:34.276349 kernel: raid6: avx2x2 gen() 28193 MB/s Mar 17 17:38:34.293457 kernel: raid6: avx2x1 gen() 25434 MB/s Mar 17 17:38:34.293489 kernel: raid6: using algorithm avx2x4 gen() 28838 MB/s Mar 17 17:38:34.311598 kernel: raid6: .... xor() 7163 MB/s, rmw enabled Mar 17 17:38:34.311643 kernel: raid6: using avx2x2 recovery algorithm Mar 17 17:38:34.338337 kernel: xor: automatically using best checksumming function avx Mar 17 17:38:34.496363 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 17 17:38:34.510816 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:38:34.521497 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:38:34.534190 systemd-udevd[414]: Using default interface naming scheme 'v255'. Mar 17 17:38:34.538892 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:38:34.549468 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 17 17:38:34.563162 dracut-pre-trigger[422]: rd.md=0: removing MD RAID activation Mar 17 17:38:34.594900 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:38:34.602435 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:38:34.668018 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:38:34.679594 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 17 17:38:34.699350 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 17 17:38:34.702411 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:38:34.705076 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:38:34.708442 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Mar 17 17:38:34.726856 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 17:38:34.726881 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Mar 17 17:38:34.727072 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 17:38:34.708403 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:38:34.724752 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 17 17:38:34.735660 kernel: AVX2 version of gcm_enc/dec engaged. Mar 17 17:38:34.735696 kernel: AES CTR mode by8 optimization enabled Mar 17 17:38:34.741857 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:38:34.747143 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:38:34.747346 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:38:34.748995 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:38:34.759455 kernel: libata version 3.00 loaded. Mar 17 17:38:34.751191 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:38:34.751372 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:38:34.761764 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:38:34.772420 kernel: BTRFS: device fsid 2b8ebefd-e897-48f6-96d5-0893fbb7c64a devid 1 transid 40 /dev/vda3 scanned by (udev-worker) (471) Mar 17 17:38:34.772455 kernel: ahci 0000:00:1f.2: version 3.0 Mar 17 17:38:34.796467 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Mar 17 17:38:34.796490 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Mar 17 17:38:34.796712 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Mar 17 17:38:34.796900 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (459) Mar 17 17:38:34.796917 kernel: scsi host0: ahci Mar 17 17:38:34.797129 kernel: scsi host1: ahci Mar 17 17:38:34.797365 kernel: scsi host2: ahci Mar 17 17:38:34.797577 kernel: scsi host3: ahci Mar 17 17:38:34.797768 kernel: scsi host4: ahci Mar 17 17:38:34.797972 kernel: scsi host5: ahci Mar 17 17:38:34.798164 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 Mar 17 17:38:34.798182 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 Mar 17 17:38:34.798197 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 Mar 17 17:38:34.798217 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 Mar 17 17:38:34.798232 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 Mar 17 17:38:34.798247 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 Mar 17 17:38:34.780675 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:38:34.811843 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 17 17:38:34.836085 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:38:34.843677 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 17 17:38:34.850862 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 17 17:38:34.857414 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 17 17:38:34.860610 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 17 17:38:34.875454 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 17 17:38:34.878643 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:38:34.895377 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:38:35.071328 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 17:38:35.110391 kernel: ata4: SATA link down (SStatus 0 SControl 300) Mar 17 17:38:35.110470 kernel: ata6: SATA link down (SStatus 0 SControl 300) Mar 17 17:38:35.110483 kernel: ata2: SATA link down (SStatus 0 SControl 300) Mar 17 17:38:35.110503 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Mar 17 17:38:35.112322 kernel: ata1: SATA link down (SStatus 0 SControl 300) Mar 17 17:38:35.112343 kernel: ata5: SATA link down (SStatus 0 SControl 300) Mar 17 17:38:35.113327 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Mar 17 17:38:35.114561 kernel: ata3.00: applying bridge limits Mar 17 17:38:35.114581 kernel: ata3.00: configured for UDMA/100 Mar 17 17:38:35.115331 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Mar 17 17:38:35.126332 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 17:38:35.158355 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Mar 17 17:38:35.176563 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 17 17:38:35.176614 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Mar 17 17:38:36.131327 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 17:38:36.131813 disk-uuid[575]: The operation has completed successfully. Mar 17 17:38:36.162657 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 17:38:36.162779 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 17 17:38:36.183601 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 17 17:38:36.186855 sh[591]: Success Mar 17 17:38:36.199320 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Mar 17 17:38:36.232847 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 17 17:38:36.250062 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 17 17:38:36.254511 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 17 17:38:36.265899 kernel: BTRFS info (device dm-0): first mount of filesystem 2b8ebefd-e897-48f6-96d5-0893fbb7c64a Mar 17 17:38:36.265958 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:38:36.265979 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 17 17:38:36.266961 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 17 17:38:36.267734 kernel: BTRFS info (device dm-0): using free space tree Mar 17 17:38:36.272945 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 17 17:38:36.273701 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 17 17:38:36.286510 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 17 17:38:36.289202 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 17 17:38:36.296704 kernel: BTRFS info (device vda6): first mount of filesystem 7b241d32-136b-4fe3-b105-cecff2b2cf64 Mar 17 17:38:36.296744 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:38:36.296756 kernel: BTRFS info (device vda6): using free space tree Mar 17 17:38:36.300312 kernel: BTRFS info (device vda6): auto enabling async discard Mar 17 17:38:36.309027 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 17:38:36.311212 kernel: BTRFS info (device vda6): last unmount of filesystem 7b241d32-136b-4fe3-b105-cecff2b2cf64 Mar 17 17:38:36.320554 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 17 17:38:36.327472 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 17 17:38:36.386018 ignition[684]: Ignition 2.20.0 Mar 17 17:38:36.386033 ignition[684]: Stage: fetch-offline Mar 17 17:38:36.386074 ignition[684]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:38:36.386085 ignition[684]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 17:38:36.386202 ignition[684]: parsed url from cmdline: "" Mar 17 17:38:36.386207 ignition[684]: no config URL provided Mar 17 17:38:36.386213 ignition[684]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 17:38:36.386225 ignition[684]: no config at "/usr/lib/ignition/user.ign" Mar 17 17:38:36.386257 ignition[684]: op(1): [started] loading QEMU firmware config module Mar 17 17:38:36.386263 ignition[684]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 17 17:38:36.392756 ignition[684]: op(1): [finished] loading QEMU firmware config module Mar 17 17:38:36.394568 ignition[684]: parsing config with SHA512: 14601503e9a3f69879c0e3a6714fdc74d1f911ba8ee890705c758ef15b0bfd63bf79f5bda7d963cad982b20b76c1006815fcbb7add5ae5da9b3627de57e67597 Mar 17 17:38:36.401285 unknown[684]: fetched base config from "system" Mar 17 17:38:36.401315 unknown[684]: fetched user config from "qemu" Mar 17 17:38:36.401547 ignition[684]: fetch-offline: fetch-offline passed Mar 17 17:38:36.403692 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:38:36.401653 ignition[684]: Ignition finished successfully Mar 17 17:38:36.420052 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:38:36.433581 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:38:36.456643 systemd-networkd[781]: lo: Link UP Mar 17 17:38:36.456653 systemd-networkd[781]: lo: Gained carrier Mar 17 17:38:36.458404 systemd-networkd[781]: Enumeration completed Mar 17 17:38:36.458619 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:38:36.458834 systemd-networkd[781]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:38:36.458838 systemd-networkd[781]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:38:36.459816 systemd-networkd[781]: eth0: Link UP Mar 17 17:38:36.459820 systemd-networkd[781]: eth0: Gained carrier Mar 17 17:38:36.459826 systemd-networkd[781]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:38:36.462146 systemd[1]: Reached target network.target - Network. Mar 17 17:38:36.465656 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 17 17:38:36.472548 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 17 17:38:36.480379 systemd-networkd[781]: eth0: DHCPv4 address 10.0.0.28/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 17 17:38:36.487595 ignition[783]: Ignition 2.20.0 Mar 17 17:38:36.487613 ignition[783]: Stage: kargs Mar 17 17:38:36.487825 ignition[783]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:38:36.487841 ignition[783]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 17:38:36.488658 ignition[783]: kargs: kargs passed Mar 17 17:38:36.488717 ignition[783]: Ignition finished successfully Mar 17 17:38:36.492435 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 17 17:38:36.505667 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 17 17:38:36.517829 ignition[792]: Ignition 2.20.0 Mar 17 17:38:36.517843 ignition[792]: Stage: disks Mar 17 17:38:36.518017 ignition[792]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:38:36.518029 ignition[792]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 17:38:36.521285 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 17 17:38:36.518673 ignition[792]: disks: disks passed Mar 17 17:38:36.522984 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 17 17:38:36.518725 ignition[792]: Ignition finished successfully Mar 17 17:38:36.524757 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 17 17:38:36.526636 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:38:36.528745 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:38:36.530443 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:38:36.544661 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 17 17:38:36.556534 systemd-resolved[224]: Detected conflict on linux IN A 10.0.0.28 Mar 17 17:38:36.556549 systemd-resolved[224]: Hostname conflict, changing published hostname from 'linux' to 'linux7'. Mar 17 17:38:36.559638 systemd-fsck[803]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 17 17:38:36.566076 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 17 17:38:36.578553 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 17 17:38:36.667314 kernel: EXT4-fs (vda9): mounted filesystem 345fc709-8965-4219-b368-16e508c3d632 r/w with ordered data mode. Quota mode: none. Mar 17 17:38:36.667763 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 17 17:38:36.668611 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 17 17:38:36.680504 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:38:36.683533 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 17 17:38:36.688707 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (811) Mar 17 17:38:36.685325 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 17 17:38:36.695620 kernel: BTRFS info (device vda6): first mount of filesystem 7b241d32-136b-4fe3-b105-cecff2b2cf64 Mar 17 17:38:36.695645 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:38:36.695666 kernel: BTRFS info (device vda6): using free space tree Mar 17 17:38:36.695680 kernel: BTRFS info (device vda6): auto enabling async discard Mar 17 17:38:36.685379 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 17:38:36.685409 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:38:36.693183 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 17 17:38:36.696444 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 17 17:38:36.700466 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:38:36.737625 initrd-setup-root[835]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 17:38:36.742953 initrd-setup-root[842]: cut: /sysroot/etc/group: No such file or directory Mar 17 17:38:36.747287 initrd-setup-root[849]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 17:38:36.751373 initrd-setup-root[856]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 17:38:36.848050 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 17 17:38:36.859480 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 17 17:38:36.861496 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 17 17:38:36.868312 kernel: BTRFS info (device vda6): last unmount of filesystem 7b241d32-136b-4fe3-b105-cecff2b2cf64 Mar 17 17:38:36.889939 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 17 17:38:36.937270 ignition[928]: INFO : Ignition 2.20.0 Mar 17 17:38:36.937270 ignition[928]: INFO : Stage: mount Mar 17 17:38:36.939232 ignition[928]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:38:36.939232 ignition[928]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 17:38:36.939232 ignition[928]: INFO : mount: mount passed Mar 17 17:38:36.939232 ignition[928]: INFO : Ignition finished successfully Mar 17 17:38:36.945622 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 17 17:38:36.958474 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 17 17:38:37.265837 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 17 17:38:37.273611 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:38:37.325325 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (938) Mar 17 17:38:37.325375 kernel: BTRFS info (device vda6): first mount of filesystem 7b241d32-136b-4fe3-b105-cecff2b2cf64 Mar 17 17:38:37.327793 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 17 17:38:37.327819 kernel: BTRFS info (device vda6): using free space tree Mar 17 17:38:37.349333 kernel: BTRFS info (device vda6): auto enabling async discard Mar 17 17:38:37.351642 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:38:37.378033 ignition[955]: INFO : Ignition 2.20.0 Mar 17 17:38:37.378033 ignition[955]: INFO : Stage: files Mar 17 17:38:37.379981 ignition[955]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:38:37.379981 ignition[955]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 17:38:37.379981 ignition[955]: DEBUG : files: compiled without relabeling support, skipping Mar 17 17:38:37.379981 ignition[955]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 17:38:37.379981 ignition[955]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 17:38:37.386887 ignition[955]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 17:38:37.386887 ignition[955]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 17:38:37.386887 ignition[955]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 17:38:37.386887 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:38:37.386887 ignition[955]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:38:37.386887 ignition[955]: INFO : files: op(4): [started] processing unit "etcd-member.service" Mar 17 17:38:37.386887 ignition[955]: 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" Mar 17 17:38:37.386887 ignition[955]: 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" Mar 17 17:38:37.386887 ignition[955]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Mar 17 17:38:37.386887 ignition[955]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Mar 17 17:38:37.386887 ignition[955]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 17 17:38:37.386887 ignition[955]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 17 17:38:37.386887 ignition[955]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Mar 17 17:38:37.386887 ignition[955]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Mar 17 17:38:37.383350 unknown[955]: wrote ssh authorized keys file for user: core Mar 17 17:38:37.434536 ignition[955]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 17 17:38:37.443008 ignition[955]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 17 17:38:37.445177 ignition[955]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Mar 17 17:38:37.445177 ignition[955]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Mar 17 17:38:37.445177 ignition[955]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Mar 17 17:38:37.445177 ignition[955]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:38:37.445177 ignition[955]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:38:37.445177 ignition[955]: INFO : files: files passed Mar 17 17:38:37.445177 ignition[955]: INFO : Ignition finished successfully Mar 17 17:38:37.459230 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 17 17:38:37.469637 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 17 17:38:37.472774 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 17 17:38:37.473884 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 17:38:37.474032 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 17 17:38:37.491278 initrd-setup-root-after-ignition[984]: grep: /sysroot/oem/oem-release: No such file or directory Mar 17 17:38:37.494997 initrd-setup-root-after-ignition[986]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:38:37.494997 initrd-setup-root-after-ignition[986]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:38:37.498472 initrd-setup-root-after-ignition[990]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:38:37.500001 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:38:37.501766 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 17 17:38:37.514712 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 17 17:38:37.540263 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 17:38:37.540460 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 17 17:38:37.542984 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 17 17:38:37.545398 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 17 17:38:37.545532 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 17 17:38:37.557542 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 17 17:38:37.573567 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:38:37.583659 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 17 17:38:37.597629 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:38:37.599998 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:38:37.600196 systemd[1]: Stopped target timers.target - Timer Units. Mar 17 17:38:37.602633 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 17:38:37.602807 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:38:37.604668 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 17 17:38:37.605047 systemd[1]: Stopped target basic.target - Basic System. Mar 17 17:38:37.605404 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 17 17:38:37.605912 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:38:37.606253 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 17 17:38:37.606780 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 17 17:38:37.607123 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:38:37.607733 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 17 17:38:37.608070 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 17 17:38:37.608599 systemd[1]: Stopped target swap.target - Swaps. Mar 17 17:38:37.608897 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 17:38:37.609044 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:38:37.629062 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:38:37.629256 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:38:37.631313 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 17 17:38:37.631467 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:38:37.633733 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 17:38:37.633897 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 17 17:38:37.637183 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 17:38:37.637360 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:38:37.641812 systemd[1]: Stopped target paths.target - Path Units. Mar 17 17:38:37.644365 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 17:38:37.648415 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:38:37.651446 systemd[1]: Stopped target slices.target - Slice Units. Mar 17 17:38:37.653605 systemd[1]: Stopped target sockets.target - Socket Units. Mar 17 17:38:37.653792 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 17:38:37.653916 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:38:37.655573 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 17:38:37.655687 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:38:37.657350 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 17:38:37.657510 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:38:37.659199 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 17:38:37.659350 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 17 17:38:37.674470 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 17 17:38:37.675375 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 17 17:38:37.678532 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 17:38:37.679839 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:38:37.682588 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 17:38:37.683720 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:38:37.689266 ignition[1010]: INFO : Ignition 2.20.0 Mar 17 17:38:37.689266 ignition[1010]: INFO : Stage: umount Mar 17 17:38:37.692690 ignition[1010]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:38:37.692690 ignition[1010]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 17 17:38:37.692690 ignition[1010]: INFO : umount: umount passed Mar 17 17:38:37.692690 ignition[1010]: INFO : Ignition finished successfully Mar 17 17:38:37.690520 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 17:38:37.690639 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 17 17:38:37.699068 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 17:38:37.700085 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 17 17:38:37.703869 systemd[1]: Stopped target network.target - Network. Mar 17 17:38:37.705642 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 17:38:37.706593 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 17 17:38:37.708988 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 17:38:37.709902 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 17 17:38:37.711888 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 17:38:37.711934 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 17 17:38:37.714982 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 17 17:38:37.716001 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 17 17:38:37.718445 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 17 17:38:37.720655 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 17 17:38:37.724345 systemd-networkd[781]: eth0: DHCPv6 lease lost Mar 17 17:38:37.724407 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 17:38:37.726976 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 17:38:37.728170 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 17 17:38:37.731102 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 17:38:37.732284 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 17 17:38:37.736526 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 17:38:37.737822 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 17 17:38:37.742416 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 17:38:37.742495 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:38:37.745961 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 17:38:37.746944 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 17 17:38:37.761487 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 17 17:38:37.762487 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 17:38:37.762552 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:38:37.762844 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 17:38:37.762893 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:38:37.766691 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 17:38:37.766743 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 17 17:38:37.769175 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 17 17:38:37.769223 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:38:37.771444 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:38:37.782360 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 17:38:37.782510 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 17 17:38:37.801038 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 17:38:37.801237 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:38:37.802407 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 17:38:37.802472 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 17 17:38:37.804443 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 17:38:37.804480 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:38:37.806394 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 17:38:37.806451 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:38:37.807143 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 17:38:37.807187 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 17 17:38:37.813788 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:38:37.813838 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:38:37.834496 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 17 17:38:37.835571 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 17 17:38:37.835636 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:38:37.837925 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Mar 17 17:38:37.837973 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:38:37.840122 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 17:38:37.840169 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:38:37.842593 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:38:37.842646 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:38:37.856788 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 17:38:37.856927 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 17 17:38:37.858166 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 17 17:38:37.875488 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 17 17:38:37.882572 systemd[1]: Switching root. Mar 17 17:38:37.915848 systemd-journald[193]: Journal stopped Mar 17 17:38:38.767514 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). Mar 17 17:38:38.767601 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 17:38:38.767630 kernel: SELinux: policy capability open_perms=1 Mar 17 17:38:38.767645 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 17:38:38.767659 kernel: SELinux: policy capability always_check_network=0 Mar 17 17:38:38.767674 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 17:38:38.767693 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 17:38:38.767714 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 17:38:38.767729 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 17:38:38.767743 kernel: audit: type=1403 audit(1742233117.996:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 17:38:38.767759 systemd[1]: Successfully loaded SELinux policy in 39.126ms. Mar 17 17:38:38.767777 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 12.271ms. Mar 17 17:38:38.767794 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Mar 17 17:38:38.767809 systemd[1]: Detected virtualization kvm. Mar 17 17:38:38.767834 systemd[1]: Detected architecture x86-64. Mar 17 17:38:38.767850 systemd[1]: Detected first boot. Mar 17 17:38:38.767865 systemd[1]: Initializing machine ID from VM UUID. Mar 17 17:38:38.767881 zram_generator::config[1055]: No configuration found. Mar 17 17:38:38.767897 systemd[1]: Populated /etc with preset unit settings. Mar 17 17:38:38.767913 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 17:38:38.767928 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 17 17:38:38.767943 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 17:38:38.767964 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 17 17:38:38.767986 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 17 17:38:38.768002 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 17 17:38:38.768017 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 17 17:38:38.768033 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 17 17:38:38.768049 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 17 17:38:38.768067 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 17 17:38:38.768089 systemd[1]: Created slice user.slice - User and Session Slice. Mar 17 17:38:38.768105 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:38:38.768121 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:38:38.768136 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 17 17:38:38.768152 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 17 17:38:38.768167 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 17 17:38:38.768183 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:38:38.768199 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 17 17:38:38.768218 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:38:38.768233 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 17 17:38:38.768249 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 17 17:38:38.768265 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 17 17:38:38.768280 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 17 17:38:38.768311 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:38:38.768327 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:38:38.768349 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:38:38.768364 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:38:38.768380 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 17 17:38:38.768402 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 17 17:38:38.768418 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:38:38.768434 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:38:38.768450 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:38:38.768466 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 17 17:38:38.768481 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 17 17:38:38.768497 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 17 17:38:38.768516 systemd[1]: Mounting media.mount - External Media Directory... Mar 17 17:38:38.768531 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:38.768546 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 17 17:38:38.768562 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 17 17:38:38.768578 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 17 17:38:38.768594 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). Mar 17 17:38:38.768609 systemd[1]: Reached target machines.target - Containers. Mar 17 17:38:38.768624 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 17 17:38:38.768643 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:38:38.768659 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:38:38.768674 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 17 17:38:38.768692 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:38:38.768708 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:38:38.768723 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:38:38.768739 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 17 17:38:38.768754 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:38:38.768770 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 17:38:38.768789 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 17:38:38.768805 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 17 17:38:38.768820 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 17:38:38.768835 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 17:38:38.768851 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:38:38.768867 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:38:38.768882 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 17 17:38:38.768898 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 17 17:38:38.768917 kernel: fuse: init (API version 7.39) Mar 17 17:38:38.768932 kernel: loop: module loaded Mar 17 17:38:38.768947 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:38:38.768985 systemd-journald[1118]: Collecting audit messages is disabled. Mar 17 17:38:38.769013 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 17:38:38.769029 systemd[1]: Stopped verity-setup.service. Mar 17 17:38:38.769045 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:38.769063 systemd-journald[1118]: Journal started Mar 17 17:38:38.769104 systemd-journald[1118]: Runtime Journal (/run/log/journal/c17ce3911229402898d691c297a5d4f0) is 6.0M, max 48.4M, 42.3M free. Mar 17 17:38:38.534564 systemd[1]: Queued start job for default target multi-user.target. Mar 17 17:38:38.560421 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 17 17:38:38.560892 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 17:38:38.776215 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:38:38.775159 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 17 17:38:38.776745 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 17 17:38:38.778121 systemd[1]: Mounted media.mount - External Media Directory. Mar 17 17:38:38.779313 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 17 17:38:38.780649 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 17 17:38:38.781943 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 17 17:38:38.783326 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:38:38.784961 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 17:38:38.785198 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 17 17:38:38.787025 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:38:38.787254 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:38:38.788939 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:38:38.789187 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:38:38.790861 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 17:38:38.791081 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 17 17:38:38.792736 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:38:38.792982 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:38:38.794556 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:38:38.806522 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 17 17:38:38.808318 kernel: ACPI: bus type drm_connector registered Mar 17 17:38:38.808950 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 17 17:38:38.810695 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:38:38.810871 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:38:38.824323 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 17 17:38:38.833397 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 17 17:38:38.835790 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 17 17:38:38.837098 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 17:38:38.837129 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:38:38.839129 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Mar 17 17:38:38.841446 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 17 17:38:38.845725 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 17 17:38:38.847222 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:38:38.849654 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 17 17:38:38.854434 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 17 17:38:38.855835 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:38:38.859570 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 17 17:38:38.860811 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:38:38.862499 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:38:38.867475 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 17 17:38:38.872024 systemd-journald[1118]: Time spent on flushing to /var/log/journal/c17ce3911229402898d691c297a5d4f0 is 13.354ms for 925 entries. Mar 17 17:38:38.872024 systemd-journald[1118]: System Journal (/var/log/journal/c17ce3911229402898d691c297a5d4f0) is 8.0M, max 195.6M, 187.6M free. Mar 17 17:38:39.093353 systemd-journald[1118]: Received client request to flush runtime journal. Mar 17 17:38:39.093409 kernel: loop0: detected capacity change from 0 to 140992 Mar 17 17:38:39.093427 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 17 17:38:39.093444 kernel: loop1: detected capacity change from 0 to 138184 Mar 17 17:38:39.093475 kernel: loop2: detected capacity change from 0 to 140992 Mar 17 17:38:39.093492 kernel: loop3: detected capacity change from 0 to 138184 Mar 17 17:38:38.873491 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 17 17:38:38.876644 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:38:38.896378 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 17 17:38:38.897677 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 17 17:38:38.899149 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 17 17:38:38.905027 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 17 17:38:38.931175 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:38:38.931589 systemd-tmpfiles[1162]: ACLs are not supported, ignoring. Mar 17 17:38:38.931602 systemd-tmpfiles[1162]: ACLs are not supported, ignoring. Mar 17 17:38:38.935779 udevadm[1169]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 17 17:38:38.937732 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:38:39.078731 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 17 17:38:39.081059 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 17 17:38:39.093676 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Mar 17 17:38:39.095881 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 17 17:38:39.097515 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 17 17:38:39.106573 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 17 17:38:39.154871 (sd-merge)[1181]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Mar 17 17:38:39.155441 (sd-merge)[1181]: Merged extensions into '/usr'. Mar 17 17:38:39.169072 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 17 17:38:39.189271 systemd[1]: Starting ensure-sysext.service... Mar 17 17:38:39.200612 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 17 17:38:39.205156 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:38:39.207944 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:38:39.253220 systemd[1]: Reloading requested from client PID 1192 ('systemctl') (unit ensure-sysext.service)... Mar 17 17:38:39.253241 systemd[1]: Reloading... Mar 17 17:38:39.258410 systemd-tmpfiles[1195]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 17:38:39.259229 systemd-tmpfiles[1195]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 17 17:38:39.259534 systemd-tmpfiles[1194]: ACLs are not supported, ignoring. Mar 17 17:38:39.259828 systemd-tmpfiles[1194]: ACLs are not supported, ignoring. Mar 17 17:38:39.260619 systemd-tmpfiles[1195]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 17:38:39.261100 systemd-tmpfiles[1195]: ACLs are not supported, ignoring. Mar 17 17:38:39.261258 systemd-tmpfiles[1195]: ACLs are not supported, ignoring. Mar 17 17:38:39.266802 systemd-tmpfiles[1195]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:38:39.266815 systemd-tmpfiles[1195]: Skipping /boot Mar 17 17:38:39.278068 systemd-tmpfiles[1195]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:38:39.278130 systemd-tmpfiles[1195]: Skipping /boot Mar 17 17:38:39.327338 zram_generator::config[1229]: No configuration found. Mar 17 17:38:39.455536 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. Mar 17 17:38:39.506256 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 17:38:39.506820 systemd[1]: Reloading finished in 253 ms. Mar 17 17:38:39.528798 ldconfig[1149]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 17:38:39.539594 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 17 17:38:39.541359 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Mar 17 17:38:39.554812 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:38:39.556525 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:38:39.570943 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 17 17:38:39.574422 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 17 17:38:39.578677 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 17 17:38:39.583618 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:38:39.594045 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 17 17:38:39.597006 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:39.597178 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:38:39.598507 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:38:39.601186 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:38:39.607890 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:38:39.611244 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:38:39.611490 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:39.615543 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 17 17:38:39.632611 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:38:39.632829 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:38:39.639212 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:39.639445 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:38:39.641224 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:38:39.643489 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:38:39.643638 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:39.645167 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:38:39.645395 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:38:39.647808 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:38:39.648063 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:38:39.651999 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:38:39.652192 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:38:39.659018 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:38:39.659483 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:38:39.664208 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 17 17:38:39.669998 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:39.670614 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:38:39.682546 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:38:39.691410 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:38:39.694599 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:38:39.697388 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:38:39.713027 augenrules[1309]: No rules Mar 17 17:38:39.699424 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:38:39.699608 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 17 17:38:39.703684 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 17 17:38:39.707357 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 17 17:38:39.711357 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 17:38:39.711698 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 17 17:38:39.714820 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:38:39.715065 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:38:39.719051 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:38:39.719591 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:38:39.722001 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:38:39.722217 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:38:39.724540 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:38:39.724771 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:38:39.730237 systemd[1]: Finished ensure-sysext.service. Mar 17 17:38:39.746620 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 17 17:38:39.749569 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:38:39.749671 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:38:39.758618 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 17 17:38:39.759988 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). Mar 17 17:38:39.778933 systemd-resolved[1270]: Positive Trust Anchors: Mar 17 17:38:39.778950 systemd-resolved[1270]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:38:39.778983 systemd-resolved[1270]: 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 Mar 17 17:38:39.782914 systemd-resolved[1270]: Defaulting to hostname 'linux'. Mar 17 17:38:39.784857 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:38:39.786409 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:38:39.836844 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 17 17:38:39.838700 systemd[1]: Reached target time-set.target - System Time Set. Mar 17 17:38:39.939942 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 17 17:38:39.958682 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:38:39.961904 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 17 17:38:39.983535 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 17 17:38:39.989968 systemd-udevd[1327]: Using default interface naming scheme 'v255'. Mar 17 17:38:40.012407 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:38:40.023475 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:38:40.056138 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 17 17:38:40.119850 systemd-networkd[1335]: lo: Link UP Mar 17 17:38:40.119864 systemd-networkd[1335]: lo: Gained carrier Mar 17 17:38:40.127272 systemd-networkd[1335]: Enumeration completed Mar 17 17:38:40.128721 systemd-networkd[1335]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:38:40.128725 systemd-networkd[1335]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:38:40.129473 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:38:40.131279 systemd[1]: Reached target network.target - Network. Mar 17 17:38:40.135328 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1345) Mar 17 17:38:40.139997 systemd-networkd[1335]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:38:40.140046 systemd-networkd[1335]: eth0: Link UP Mar 17 17:38:40.140052 systemd-networkd[1335]: eth0: Gained carrier Mar 17 17:38:40.140065 systemd-networkd[1335]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:38:40.141373 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Mar 17 17:38:40.145565 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 17 17:38:40.148022 kernel: ACPI: button: Power Button [PWRF] Mar 17 17:38:40.156410 systemd-networkd[1335]: eth0: DHCPv4 address 10.0.0.28/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 17 17:38:40.158473 systemd-timesyncd[1323]: Network configuration changed, trying to establish connection. Mar 17 17:38:41.042747 systemd-timesyncd[1323]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 17 17:38:41.042796 systemd-timesyncd[1323]: Initial clock synchronization to Mon 2025-03-17 17:38:41.042593 UTC. Mar 17 17:38:41.046296 systemd-resolved[1270]: Clock change detected. Flushing caches. Mar 17 17:38:41.046798 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 17 17:38:41.059087 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Mar 17 17:38:41.063764 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Mar 17 17:38:41.063997 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Mar 17 17:38:41.059869 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 17 17:38:41.069662 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Mar 17 17:38:41.078122 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 17 17:38:41.129605 kernel: mousedev: PS/2 mouse device common for all mice Mar 17 17:38:41.154103 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:38:41.213944 kernel: kvm_amd: TSC scaling supported Mar 17 17:38:41.214019 kernel: kvm_amd: Nested Virtualization enabled Mar 17 17:38:41.214033 kernel: kvm_amd: Nested Paging enabled Mar 17 17:38:41.215079 kernel: kvm_amd: LBR virtualization supported Mar 17 17:38:41.215178 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Mar 17 17:38:41.216640 kernel: kvm_amd: Virtual GIF supported Mar 17 17:38:41.237600 kernel: EDAC MC: Ver: 3.0.0 Mar 17 17:38:41.281932 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 17 17:38:41.290949 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:38:41.303743 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 17 17:38:41.314597 lvm[1374]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:38:41.350466 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 17 17:38:41.352269 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:38:41.353497 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:38:41.354846 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 17 17:38:41.356166 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 17 17:38:41.357917 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 17 17:38:41.359338 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 17 17:38:41.360715 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 17 17:38:41.362049 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 17:38:41.362094 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:38:41.363064 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:38:41.365381 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 17 17:38:41.368963 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 17 17:38:41.391926 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 17 17:38:41.394745 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 17 17:38:41.396412 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 17 17:38:41.398028 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:38:41.399372 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:38:41.400747 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:38:41.400786 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:38:41.402123 systemd[1]: Starting containerd.service - containerd container runtime... Mar 17 17:38:41.404898 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 17 17:38:41.408254 lvm[1378]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:38:41.409239 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 17 17:38:41.413749 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 17 17:38:41.417176 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). Mar 17 17:38:41.421451 jq[1381]: false Mar 17 17:38:41.421837 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 17 17:38:41.424543 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 17 17:38:41.428773 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 17 17:38:41.430735 dbus-daemon[1380]: [system] SELinux support is enabled Mar 17 17:38:41.439964 extend-filesystems[1382]: Found loop2 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found loop3 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found sr0 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda1 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda2 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda3 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found usr Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda4 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda6 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda7 Mar 17 17:38:41.439964 extend-filesystems[1382]: Found vda9 Mar 17 17:38:41.439964 extend-filesystems[1382]: Checking size of /dev/vda9 Mar 17 17:38:41.436285 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 17 17:38:41.479213 extend-filesystems[1382]: Old size kept for /dev/vda9 Mar 17 17:38:41.438291 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 17:38:41.439189 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 17:38:41.440333 systemd[1]: Starting update-engine.service - Update Engine... Mar 17 17:38:41.481289 update_engine[1392]: I20250317 17:38:41.469020 1392 main.cc:92] Flatcar Update Engine starting Mar 17 17:38:41.481289 update_engine[1392]: I20250317 17:38:41.470199 1392 update_check_scheduler.cc:74] Next update check in 6m13s Mar 17 17:38:41.445134 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 17 17:38:41.481820 jq[1396]: true Mar 17 17:38:41.447420 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 17 17:38:41.452834 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 17 17:38:41.456239 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 17:38:41.456464 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 17 17:38:41.456843 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 17:38:41.457037 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 17 17:38:41.462115 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 17:38:41.462316 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 17 17:38:41.464088 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 17:38:41.464287 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 17 17:38:41.482941 (ntainerd)[1405]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 17 17:38:41.485376 systemd[1]: Started update-engine.service - Update Engine. Mar 17 17:38:41.487247 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). Mar 17 17:38:41.487278 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 17 17:38:41.489439 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). Mar 17 17:38:41.489459 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 17 17:38:41.493656 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1333) Mar 17 17:38:41.495136 jq[1402]: true Mar 17 17:38:41.499244 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 17 17:38:41.518264 systemd-logind[1389]: Watching system buttons on /dev/input/event1 (Power Button) Mar 17 17:38:41.519503 systemd-logind[1389]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 17 17:38:41.522869 systemd-logind[1389]: New seat seat0. Mar 17 17:38:41.527766 systemd[1]: Started systemd-logind.service - User Login Management. Mar 17 17:38:41.589287 locksmithd[1413]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 17:38:41.754646 sshd_keygen[1403]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 17:38:41.772671 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 17 17:38:41.782111 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 17 17:38:41.791811 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 17 17:38:41.793846 systemd[1]: Started sshd@0-10.0.0.28:22-10.0.0.1:55196.service - OpenSSH per-connection server daemon (10.0.0.1:55196). Mar 17 17:38:41.802458 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 17:38:41.802701 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 17 17:38:41.818228 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 17 17:38:41.870550 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 17 17:38:41.889012 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 17 17:38:41.892828 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 17 17:38:41.894546 systemd[1]: Reached target getty.target - Login Prompts. Mar 17 17:38:41.897681 bash[1430]: Updated "/home/core/.ssh/authorized_keys" Mar 17 17:38:41.899671 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 17 17:38:41.902534 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 17 17:38:41.920064 sshd[1451]: Accepted publickey for core from 10.0.0.1 port 55196 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:38:41.922671 sshd-session[1451]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:38:41.933163 systemd-logind[1389]: New session 1 of user core. Mar 17 17:38:41.933963 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 17 17:38:41.963950 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 17 17:38:41.977786 containerd[1405]: time="2025-03-17T17:38:41.977698508Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Mar 17 17:38:41.987183 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 17 17:38:42.005100 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 17 17:38:42.006536 containerd[1405]: time="2025-03-17T17:38:42.006488800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:38:42.008754 containerd[1405]: time="2025-03-17T17:38:42.008713040Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.83-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:38:42.008754 containerd[1405]: time="2025-03-17T17:38:42.008750600Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 17:38:42.008830 containerd[1405]: time="2025-03-17T17:38:42.008773143Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 17:38:42.009015 containerd[1405]: time="2025-03-17T17:38:42.008981654Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Mar 17 17:38:42.009015 containerd[1405]: time="2025-03-17T17:38:42.009008334Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009111 containerd[1405]: time="2025-03-17T17:38:42.009084797Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009111 containerd[1405]: time="2025-03-17T17:38:42.009106147Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009376 containerd[1405]: time="2025-03-17T17:38:42.009348031Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009398 containerd[1405]: time="2025-03-17T17:38:42.009372486Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009398 containerd[1405]: time="2025-03-17T17:38:42.009390270Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009439 containerd[1405]: time="2025-03-17T17:38:42.009402743Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009545 containerd[1405]: time="2025-03-17T17:38:42.009521205Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009883 containerd[1405]: time="2025-03-17T17:38:42.009857536Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:38:42.009983 (systemd)[1464]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 17:38:42.010213 containerd[1405]: time="2025-03-17T17:38:42.010012266Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:38:42.010213 containerd[1405]: time="2025-03-17T17:38:42.010029749Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 17:38:42.010213 containerd[1405]: time="2025-03-17T17:38:42.010151617Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 17:38:42.010279 containerd[1405]: time="2025-03-17T17:38:42.010223031Z" level=info msg="metadata content store policy set" policy=shared Mar 17 17:38:42.089450 containerd[1405]: time="2025-03-17T17:38:42.089394768Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 17:38:42.089554 containerd[1405]: time="2025-03-17T17:38:42.089486099Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 17:38:42.089554 containerd[1405]: time="2025-03-17T17:38:42.089509123Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Mar 17 17:38:42.089554 containerd[1405]: time="2025-03-17T17:38:42.089533017Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Mar 17 17:38:42.089752 containerd[1405]: time="2025-03-17T17:38:42.089554488Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 17:38:42.089873 containerd[1405]: time="2025-03-17T17:38:42.089844812Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 17:38:42.090196 containerd[1405]: time="2025-03-17T17:38:42.090145516Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 17:38:42.090331 containerd[1405]: time="2025-03-17T17:38:42.090295136Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Mar 17 17:38:42.090331 containerd[1405]: time="2025-03-17T17:38:42.090316547Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Mar 17 17:38:42.090410 containerd[1405]: time="2025-03-17T17:38:42.090335713Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Mar 17 17:38:42.090410 containerd[1405]: time="2025-03-17T17:38:42.090351933Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090410 containerd[1405]: time="2025-03-17T17:38:42.090369185Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090410 containerd[1405]: time="2025-03-17T17:38:42.090384865Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090410 containerd[1405]: time="2025-03-17T17:38:42.090402588Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090529 containerd[1405]: time="2025-03-17T17:38:42.090419680Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090529 containerd[1405]: time="2025-03-17T17:38:42.090437393Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090529 containerd[1405]: time="2025-03-17T17:38:42.090455126Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090529 containerd[1405]: time="2025-03-17T17:38:42.090471297Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 17:38:42.090529 containerd[1405]: time="2025-03-17T17:38:42.090494931Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090529 containerd[1405]: time="2025-03-17T17:38:42.090511141Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090529 containerd[1405]: time="2025-03-17T17:38:42.090525819Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090541188Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090556537Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090640935Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090657606Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090674137Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090691570Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090710585Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090726465Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090743116Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.090767 containerd[1405]: time="2025-03-17T17:38:42.090760218Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.091008 containerd[1405]: time="2025-03-17T17:38:42.090779384Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Mar 17 17:38:42.091008 containerd[1405]: time="2025-03-17T17:38:42.090805233Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.091802 containerd[1405]: time="2025-03-17T17:38:42.091125393Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.091802 containerd[1405]: time="2025-03-17T17:38:42.091161581Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 17:38:42.092142 containerd[1405]: time="2025-03-17T17:38:42.091863567Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 17:38:42.092220 containerd[1405]: time="2025-03-17T17:38:42.092173208Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Mar 17 17:38:42.092220 containerd[1405]: time="2025-03-17T17:38:42.092199517Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 17:38:42.092273 containerd[1405]: time="2025-03-17T17:38:42.092222200Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Mar 17 17:38:42.092273 containerd[1405]: time="2025-03-17T17:38:42.092237308Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.092273 containerd[1405]: time="2025-03-17T17:38:42.092260652Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Mar 17 17:38:42.092344 containerd[1405]: time="2025-03-17T17:38:42.092278465Z" level=info msg="NRI interface is disabled by configuration." Mar 17 17:38:42.092344 containerd[1405]: time="2025-03-17T17:38:42.092296188Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 17:38:42.092886 containerd[1405]: time="2025-03-17T17:38:42.092811224Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Mar 17 17:38:42.093121 containerd[1405]: time="2025-03-17T17:38:42.092890393Z" level=info msg="Connect containerd service" Mar 17 17:38:42.093121 containerd[1405]: time="2025-03-17T17:38:42.092947790Z" level=info msg="using legacy CRI server" Mar 17 17:38:42.093121 containerd[1405]: time="2025-03-17T17:38:42.092956967Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 17 17:38:42.093121 containerd[1405]: time="2025-03-17T17:38:42.093100507Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 17:38:42.093968 containerd[1405]: time="2025-03-17T17:38:42.093932056Z" 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" Mar 17 17:38:42.094129 containerd[1405]: time="2025-03-17T17:38:42.094073300Z" level=info msg="Start subscribing containerd event" Mar 17 17:38:42.094168 containerd[1405]: time="2025-03-17T17:38:42.094149604Z" level=info msg="Start recovering state" Mar 17 17:38:42.094267 containerd[1405]: time="2025-03-17T17:38:42.094238851Z" level=info msg="Start event monitor" Mar 17 17:38:42.094267 containerd[1405]: time="2025-03-17T17:38:42.094262896Z" level=info msg="Start snapshots syncer" Mar 17 17:38:42.094340 containerd[1405]: time="2025-03-17T17:38:42.094276171Z" level=info msg="Start cni network conf syncer for default" Mar 17 17:38:42.094340 containerd[1405]: time="2025-03-17T17:38:42.094286260Z" level=info msg="Start streaming server" Mar 17 17:38:42.094392 containerd[1405]: time="2025-03-17T17:38:42.094353706Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 17:38:42.096390 containerd[1405]: time="2025-03-17T17:38:42.094420812Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 17:38:42.096390 containerd[1405]: time="2025-03-17T17:38:42.096051750Z" level=info msg="containerd successfully booted in 0.119779s" Mar 17 17:38:42.094682 systemd[1]: Started containerd.service - containerd container runtime. Mar 17 17:38:42.151675 systemd[1464]: Queued start job for default target default.target. Mar 17 17:38:42.161902 systemd[1464]: Created slice app.slice - User Application Slice. Mar 17 17:38:42.161931 systemd[1464]: Reached target paths.target - Paths. Mar 17 17:38:42.161945 systemd[1464]: Reached target timers.target - Timers. Mar 17 17:38:42.163650 systemd[1464]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 17 17:38:42.201777 systemd[1464]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:38:42.201938 systemd[1464]: Reached target sockets.target - Sockets. Mar 17 17:38:42.201960 systemd[1464]: Reached target basic.target - Basic System. Mar 17 17:38:42.202006 systemd[1464]: Reached target default.target - Main User Target. Mar 17 17:38:42.202046 systemd[1464]: Startup finished in 185ms. Mar 17 17:38:42.202638 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 17 17:38:42.205388 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 17 17:38:42.272927 systemd[1]: Started sshd@1-10.0.0.28:22-10.0.0.1:55210.service - OpenSSH per-connection server daemon (10.0.0.1:55210). Mar 17 17:38:42.358308 sshd[1477]: Accepted publickey for core from 10.0.0.1 port 55210 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:38:42.360443 sshd-session[1477]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:38:42.364933 systemd-logind[1389]: New session 2 of user core. Mar 17 17:38:42.378804 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 17 17:38:42.434442 sshd[1479]: Connection closed by 10.0.0.1 port 55210 Mar 17 17:38:42.434840 sshd-session[1477]: pam_unix(sshd:session): session closed for user core Mar 17 17:38:42.445252 systemd[1]: sshd@1-10.0.0.28:22-10.0.0.1:55210.service: Deactivated successfully. Mar 17 17:38:42.446822 systemd[1]: session-2.scope: Deactivated successfully. Mar 17 17:38:42.448183 systemd-logind[1389]: Session 2 logged out. Waiting for processes to exit. Mar 17 17:38:42.449651 systemd[1]: Started sshd@2-10.0.0.28:22-10.0.0.1:55214.service - OpenSSH per-connection server daemon (10.0.0.1:55214). Mar 17 17:38:42.451944 systemd-logind[1389]: Removed session 2. Mar 17 17:38:42.491607 sshd[1484]: Accepted publickey for core from 10.0.0.1 port 55214 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:38:42.493349 sshd-session[1484]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:38:42.497075 systemd-logind[1389]: New session 3 of user core. Mar 17 17:38:42.506688 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 17 17:38:42.521703 systemd-networkd[1335]: eth0: Gained IPv6LL Mar 17 17:38:42.525321 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 17 17:38:42.527377 systemd[1]: Reached target network-online.target - Network is Online. Mar 17 17:38:42.538863 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 17 17:38:42.541325 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 17 17:38:42.562444 sshd[1486]: Connection closed by 10.0.0.1 port 55214 Mar 17 17:38:42.562946 sshd-session[1484]: pam_unix(sshd:session): session closed for user core Mar 17 17:38:42.566463 systemd[1]: sshd@2-10.0.0.28:22-10.0.0.1:55214.service: Deactivated successfully. Mar 17 17:38:42.568258 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 17 17:38:42.568495 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 17 17:38:42.570044 systemd[1]: session-3.scope: Deactivated successfully. Mar 17 17:38:42.570672 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 17 17:38:42.572163 systemd-logind[1389]: Session 3 logged out. Waiting for processes to exit. Mar 17 17:38:42.583879 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Mar 17 17:38:42.585147 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 17 17:38:42.585815 (-wrapper)[1504]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Mar 17 17:38:42.585845 systemd-logind[1389]: Removed session 3. Mar 17 17:38:42.851815 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 17 17:38:42.853457 (dockerd)[1516]: 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 Mar 17 17:38:43.330521 dockerd[1516]: time="2025-03-17T17:38:43.330385475Z" level=info msg="Starting up" Mar 17 17:38:43.518968 dockerd[1516]: time="2025-03-17T17:38:43.518917222Z" level=info msg="Loading containers: start." Mar 17 17:38:43.697595 kernel: Initializing XFRM netlink socket Mar 17 17:38:43.805556 systemd-networkd[1335]: docker0: Link UP Mar 17 17:38:43.846682 dockerd[1516]: time="2025-03-17T17:38:43.846615737Z" level=info msg="Loading containers: done." Mar 17 17:38:43.864946 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4147954335-merged.mount: Deactivated successfully. Mar 17 17:38:43.867716 dockerd[1516]: time="2025-03-17T17:38:43.867673651Z" 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 Mar 17 17:38:43.867814 dockerd[1516]: time="2025-03-17T17:38:43.867788266Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Mar 17 17:38:43.867948 dockerd[1516]: time="2025-03-17T17:38:43.867925693Z" level=info msg="Daemon has completed initialization" Mar 17 17:38:43.908764 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 17 17:38:43.909472 dockerd[1516]: time="2025-03-17T17:38:43.908527351Z" level=info msg="API listen on /run/docker.sock" Mar 17 17:38:43.910861 etcd-wrapper[1508]: Error response from daemon: No such container: etcd-member Mar 17 17:38:43.929497 etcd-wrapper[1691]: Error response from daemon: No such container: etcd-member Mar 17 17:38:43.985774 etcd-wrapper[1711]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Mar 17 17:38:46.106513 etcd-wrapper[1711]: v3.5.16: Pulling from coreos/etcd Mar 17 17:38:46.364871 etcd-wrapper[1711]: 804c8aba2cc6: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: 2ae710cd8bfe: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: d462aa345367: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: 0f8b424aa0b9: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: d557676654e5: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: c8022d07192e: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: d858cbc252ad: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: 1069fc2daed1: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: b40161cd83fc: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: 5318d93a3a65: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: 307c1adadb60: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: fbb01d9e9dc9: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: fbfea02ac3cf: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: 8c26e4bf18e2: Pulling fs layer Mar 17 17:38:46.364871 etcd-wrapper[1711]: 1e59a65f8816: Pulling fs layer Mar 17 17:38:46.365279 etcd-wrapper[1711]: ffbd4ca5f0bd: Pulling fs layer Mar 17 17:38:46.365279 etcd-wrapper[1711]: 0f8b424aa0b9: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: d557676654e5: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: c8022d07192e: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: d858cbc252ad: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: 1069fc2daed1: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: b40161cd83fc: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: 5318d93a3a65: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: 307c1adadb60: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: fbb01d9e9dc9: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: fbfea02ac3cf: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: 8c26e4bf18e2: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: 1e59a65f8816: Waiting Mar 17 17:38:46.365279 etcd-wrapper[1711]: ffbd4ca5f0bd: Waiting Mar 17 17:38:46.633767 etcd-wrapper[1711]: 2ae710cd8bfe: Verifying Checksum Mar 17 17:38:46.633767 etcd-wrapper[1711]: 2ae710cd8bfe: Download complete Mar 17 17:38:46.726389 etcd-wrapper[1711]: 804c8aba2cc6: Download complete Mar 17 17:38:46.748185 etcd-wrapper[1711]: d462aa345367: Verifying Checksum Mar 17 17:38:46.876316 etcd-wrapper[1711]: 804c8aba2cc6: Pull complete Mar 17 17:38:46.892645 etcd-wrapper[1711]: 0f8b424aa0b9: Verifying Checksum Mar 17 17:38:46.892645 etcd-wrapper[1711]: 0f8b424aa0b9: Download complete Mar 17 17:38:46.898273 systemd[1]: var-lib-docker-overlay2-72ea4ef667b883822878ffd6b9aca54a6200af91e5a74cadeea128fe7f39e13c-merged.mount: Deactivated successfully. Mar 17 17:38:46.921888 etcd-wrapper[1711]: 2ae710cd8bfe: Pull complete Mar 17 17:38:46.971090 etcd-wrapper[1711]: c8022d07192e: Verifying Checksum Mar 17 17:38:46.971090 etcd-wrapper[1711]: c8022d07192e: Download complete Mar 17 17:38:47.016407 etcd-wrapper[1711]: d557676654e5: Verifying Checksum Mar 17 17:38:47.016407 etcd-wrapper[1711]: d557676654e5: Download complete Mar 17 17:38:47.323072 etcd-wrapper[1711]: 1069fc2daed1: Verifying Checksum Mar 17 17:38:47.323072 etcd-wrapper[1711]: 1069fc2daed1: Download complete Mar 17 17:38:47.336208 systemd[1]: var-lib-docker-overlay2-d2838a6e8d69ee9bb6334daad8f867b460c15929e83568ec0433fc2e71185fe0-merged.mount: Deactivated successfully. Mar 17 17:38:47.537958 etcd-wrapper[1711]: d462aa345367: Pull complete Mar 17 17:38:47.574496 etcd-wrapper[1711]: 0f8b424aa0b9: Pull complete Mar 17 17:38:47.582668 etcd-wrapper[1711]: 5318d93a3a65: Verifying Checksum Mar 17 17:38:47.582668 etcd-wrapper[1711]: 5318d93a3a65: Download complete Mar 17 17:38:47.600536 etcd-wrapper[1711]: d557676654e5: Pull complete Mar 17 17:38:47.628637 etcd-wrapper[1711]: c8022d07192e: Pull complete Mar 17 17:38:47.651055 etcd-wrapper[1711]: d858cbc252ad: Pull complete Mar 17 17:38:47.673894 etcd-wrapper[1711]: 1069fc2daed1: Pull complete Mar 17 17:38:47.892132 systemd[1]: var-lib-docker-overlay2-c6e206145633630da75c8f74ad0fdda1d9bc6cee5dbfb8ff74c194c9ab1583b7-merged.mount: Deactivated successfully. Mar 17 17:38:47.898121 etcd-wrapper[1711]: fbb01d9e9dc9: Verifying Checksum Mar 17 17:38:47.898121 etcd-wrapper[1711]: fbb01d9e9dc9: Download complete Mar 17 17:38:48.247101 etcd-wrapper[1711]: fbfea02ac3cf: Verifying Checksum Mar 17 17:38:48.247101 etcd-wrapper[1711]: fbfea02ac3cf: Download complete Mar 17 17:38:48.652897 etcd-wrapper[1711]: 8c26e4bf18e2: Verifying Checksum Mar 17 17:38:48.652897 etcd-wrapper[1711]: 8c26e4bf18e2: Download complete Mar 17 17:38:48.914938 etcd-wrapper[1711]: 1e59a65f8816: Download complete Mar 17 17:38:49.413973 etcd-wrapper[1711]: ffbd4ca5f0bd: Verifying Checksum Mar 17 17:38:49.413973 etcd-wrapper[1711]: ffbd4ca5f0bd: Download complete Mar 17 17:38:52.500841 etcd-wrapper[1711]: b40161cd83fc: Verifying Checksum Mar 17 17:38:52.500841 etcd-wrapper[1711]: b40161cd83fc: Download complete Mar 17 17:38:52.504720 etcd-wrapper[1711]: 307c1adadb60: Verifying Checksum Mar 17 17:38:52.504720 etcd-wrapper[1711]: 307c1adadb60: Download complete Mar 17 17:38:52.515981 systemd[1]: var-lib-docker-overlay2-d04a7b3fc621ddb8412be1257386cdd9f73c8610c70ebc5405104fb1debc51a8-merged.mount: Deactivated successfully. Mar 17 17:38:52.574916 etcd-wrapper[1711]: b40161cd83fc: Pull complete Mar 17 17:38:52.578398 systemd[1]: Started sshd@3-10.0.0.28:22-10.0.0.1:48622.service - OpenSSH per-connection server daemon (10.0.0.1:48622). Mar 17 17:38:52.589172 systemd[1]: var-lib-docker-overlay2-a4ef8f216310eefb0fb502b1a2ad4dca8f91494c22b3f550b65e7733d273c4fc-merged.mount: Deactivated successfully. Mar 17 17:38:52.603600 etcd-wrapper[1711]: 5318d93a3a65: Pull complete Mar 17 17:38:52.622429 sshd[1775]: Accepted publickey for core from 10.0.0.1 port 48622 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:38:52.622880 sshd-session[1775]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:38:52.627053 systemd-logind[1389]: New session 4 of user core. Mar 17 17:38:52.636691 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 17 17:38:52.638446 etcd-wrapper[1711]: 307c1adadb60: Pull complete Mar 17 17:38:52.690130 sshd[1787]: Connection closed by 10.0.0.1 port 48622 Mar 17 17:38:52.690416 sshd-session[1775]: pam_unix(sshd:session): session closed for user core Mar 17 17:38:52.703391 systemd[1]: sshd@3-10.0.0.28:22-10.0.0.1:48622.service: Deactivated successfully. Mar 17 17:38:52.705912 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 17:38:52.707053 systemd-logind[1389]: Session 4 logged out. Waiting for processes to exit. Mar 17 17:38:52.709797 systemd-logind[1389]: Removed session 4. Mar 17 17:38:52.717800 systemd[1]: Started sshd@4-10.0.0.28:22-10.0.0.1:48636.service - OpenSSH per-connection server daemon (10.0.0.1:48636). Mar 17 17:38:52.751490 sshd[1797]: Accepted publickey for core from 10.0.0.1 port 48636 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:38:52.753078 sshd-session[1797]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:38:52.757205 systemd-logind[1389]: New session 5 of user core. Mar 17 17:38:52.766896 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 17 17:38:52.822215 sshd[1799]: Connection closed by 10.0.0.1 port 48636 Mar 17 17:38:52.822640 sshd-session[1797]: pam_unix(sshd:session): session closed for user core Mar 17 17:38:52.826811 systemd[1]: sshd@4-10.0.0.28:22-10.0.0.1:48636.service: Deactivated successfully. Mar 17 17:38:52.828686 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 17:38:52.829271 systemd-logind[1389]: Session 5 logged out. Waiting for processes to exit. Mar 17 17:38:52.830212 systemd-logind[1389]: Removed session 5. Mar 17 17:38:52.861721 etcd-wrapper[1711]: fbb01d9e9dc9: Pull complete Mar 17 17:38:53.026865 etcd-wrapper[1711]: fbfea02ac3cf: Pull complete Mar 17 17:38:53.170581 etcd-wrapper[1711]: 8c26e4bf18e2: Pull complete Mar 17 17:38:53.194303 etcd-wrapper[1711]: 1e59a65f8816: Pull complete Mar 17 17:38:53.223703 etcd-wrapper[1711]: ffbd4ca5f0bd: Pull complete Mar 17 17:38:53.234704 etcd-wrapper[1711]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Mar 17 17:38:53.239308 etcd-wrapper[1711]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Mar 17 17:38:53.513641 systemd[1]: var-lib-docker-overlay2-484670b606a95d88a76f158c1342485298c55d2cbc9cc1476dfe98342bbb8457-merged.mount: Deactivated successfully. Mar 17 17:38:53.534671 containerd[1405]: time="2025-03-17T17:38:53.534509886Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 17:38:53.534671 containerd[1405]: time="2025-03-17T17:38:53.534600035Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 17:38:53.534671 containerd[1405]: time="2025-03-17T17:38:53.534612028Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:38:53.535180 containerd[1405]: time="2025-03-17T17:38:53.534712837Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:38:53.565857 systemd[1]: Started docker-98f9d13d7c1ee55bf44f7fcd669a48dd48c4eb71691f014e292c95d54827dedc.scope - libcontainer container 98f9d13d7c1ee55bf44f7fcd669a48dd48c4eb71691f014e292c95d54827dedc. Mar 17 17:38:53.585370 systemd-resolved[1270]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.626290Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.626448Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"c17ce3911229402898d691c297a5d4f0"} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:38:53.626478Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:38:53.626498Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:38:53.626506Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:38:53.626511Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:38:53.626537Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.626592Z","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.28:2379"]} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:38:53.626673Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Mar 17 17:38:53.626804 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.626689Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Mar 17 17:38:53.627546 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.627388Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Mar 17 17:38:53.627729 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.627545Z","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":"c17ce3911229402898d691c297a5d4f0","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.28:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"c17ce3911229402898d691c297a5d4f0=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"} Mar 17 17:38:53.787228 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.786794Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"158.839645ms"} Mar 17 17:38:53.933489 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.933098Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Mar 17 17:38:53.933489 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.933213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Mar 17 17:38:53.933489 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.933276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Mar 17 17:38:53.933489 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.933287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Mar 17 17:38:53.933489 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.933299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Mar 17 17:38:53.933489 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:53.933331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Mar 17 17:38:54.009336 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:38:54.008981Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Mar 17 17:38:54.025804 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.025491Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Mar 17 17:38:54.040558 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.040169Z","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"} Mar 17 17:38:54.046610 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.045896Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Mar 17 17:38:54.046610 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.046063Z","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"} Mar 17 17:38:54.046610 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.046206Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Mar 17 17:38:54.046610 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.046327Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Mar 17 17:38:54.046797 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.046397Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Mar 17 17:38:54.046797 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.046409Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Mar 17 17:38:54.048918 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.048752Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Mar 17 17:38:54.048918 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.048771Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Mar 17 17:38:54.049006 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.048801Z","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.28:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Mar 17 17:38:54.051993 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.051799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Mar 17 17:38:54.052065 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.051897Z","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"]} Mar 17 17:38:54.734936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.734504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Mar 17 17:38:54.734936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.734552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Mar 17 17:38:54.734936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.734589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Mar 17 17:38:54.734936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.734605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Mar 17 17:38:54.734936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.734611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Mar 17 17:38:54.734936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.734621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Mar 17 17:38:54.734936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.734628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Mar 17 17:38:54.735797 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.735399Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:c17ce3911229402898d691c297a5d4f0 ClientURLs:[http://10.0.0.28:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Mar 17 17:38:54.735797 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.735406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Mar 17 17:38:54.735797 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.735418Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Mar 17 17:38:54.735797 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.735576Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Mar 17 17:38:54.735797 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.735721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Mar 17 17:38:54.736046 systemd[1]: Started etcd-member.service - etcd (System Application Container). Mar 17 17:38:54.736551 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.736262Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Mar 17 17:38:54.736880 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 17 17:38:54.737075 systemd[1]: Startup finished in 723ms (kernel) + 4.292s (initrd) + 15.896s (userspace) = 20.912s. Mar 17 17:38:54.737199 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.736333Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Mar 17 17:38:54.737373 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.736719Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Mar 17 17:38:54.737373 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.736754Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Mar 17 17:38:54.737373 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:38:54.737002Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Mar 17 17:39:02.836294 systemd[1]: Started sshd@5-10.0.0.28:22-10.0.0.1:40182.service - OpenSSH per-connection server daemon (10.0.0.1:40182). Mar 17 17:39:02.882214 sshd[1872]: Accepted publickey for core from 10.0.0.1 port 40182 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:39:02.884411 sshd-session[1872]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:39:02.890463 systemd-logind[1389]: New session 6 of user core. Mar 17 17:39:02.904024 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 17 17:39:02.963836 sshd[1874]: Connection closed by 10.0.0.1 port 40182 Mar 17 17:39:02.964443 sshd-session[1872]: pam_unix(sshd:session): session closed for user core Mar 17 17:39:02.979664 systemd[1]: sshd@5-10.0.0.28:22-10.0.0.1:40182.service: Deactivated successfully. Mar 17 17:39:02.982111 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 17:39:02.984093 systemd-logind[1389]: Session 6 logged out. Waiting for processes to exit. Mar 17 17:39:02.995276 systemd[1]: Started sshd@6-10.0.0.28:22-10.0.0.1:40198.service - OpenSSH per-connection server daemon (10.0.0.1:40198). Mar 17 17:39:02.996657 systemd-logind[1389]: Removed session 6. Mar 17 17:39:03.032754 sshd[1879]: Accepted publickey for core from 10.0.0.1 port 40198 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:39:03.034744 sshd-session[1879]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:39:03.039384 systemd-logind[1389]: New session 7 of user core. Mar 17 17:39:03.047780 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 17 17:39:03.102243 sshd[1881]: Connection closed by 10.0.0.1 port 40198 Mar 17 17:39:03.103144 sshd-session[1879]: pam_unix(sshd:session): session closed for user core Mar 17 17:39:03.122023 systemd[1]: sshd@6-10.0.0.28:22-10.0.0.1:40198.service: Deactivated successfully. Mar 17 17:39:03.124845 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 17:39:03.126819 systemd-logind[1389]: Session 7 logged out. Waiting for processes to exit. Mar 17 17:39:03.226287 systemd[1]: Started sshd@7-10.0.0.28:22-10.0.0.1:40204.service - OpenSSH per-connection server daemon (10.0.0.1:40204). Mar 17 17:39:03.229989 systemd-logind[1389]: Removed session 7. Mar 17 17:39:03.273718 sshd[1886]: Accepted publickey for core from 10.0.0.1 port 40204 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:39:03.275929 sshd-session[1886]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:39:03.283081 systemd-logind[1389]: New session 8 of user core. Mar 17 17:39:03.295961 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 17 17:39:03.358612 sshd[1888]: Connection closed by 10.0.0.1 port 40204 Mar 17 17:39:03.359534 sshd-session[1886]: pam_unix(sshd:session): session closed for user core Mar 17 17:39:03.373808 systemd[1]: sshd@7-10.0.0.28:22-10.0.0.1:40204.service: Deactivated successfully. Mar 17 17:39:03.376827 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 17:39:03.379362 systemd-logind[1389]: Session 8 logged out. Waiting for processes to exit. Mar 17 17:39:03.393291 systemd[1]: Started sshd@8-10.0.0.28:22-10.0.0.1:40210.service - OpenSSH per-connection server daemon (10.0.0.1:40210). Mar 17 17:39:03.395226 systemd-logind[1389]: Removed session 8. Mar 17 17:39:03.439304 sshd[1893]: Accepted publickey for core from 10.0.0.1 port 40210 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:39:03.441640 sshd-session[1893]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:39:03.447653 systemd-logind[1389]: New session 9 of user core. Mar 17 17:39:03.454951 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 17 17:39:03.523713 sudo[1896]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 17 17:39:03.524171 sudo[1896]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:39:03.541127 sudo[1896]: pam_unix(sudo:session): session closed for user root Mar 17 17:39:03.543260 sshd[1895]: Connection closed by 10.0.0.1 port 40210 Mar 17 17:39:03.543986 sshd-session[1893]: pam_unix(sshd:session): session closed for user core Mar 17 17:39:03.557620 systemd[1]: sshd@8-10.0.0.28:22-10.0.0.1:40210.service: Deactivated successfully. Mar 17 17:39:03.560351 systemd[1]: session-9.scope: Deactivated successfully. Mar 17 17:39:03.563098 systemd-logind[1389]: Session 9 logged out. Waiting for processes to exit. Mar 17 17:39:03.576434 systemd[1]: Started sshd@9-10.0.0.28:22-10.0.0.1:40220.service - OpenSSH per-connection server daemon (10.0.0.1:40220). Mar 17 17:39:03.578188 systemd-logind[1389]: Removed session 9. Mar 17 17:39:03.618895 sshd[1901]: Accepted publickey for core from 10.0.0.1 port 40220 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:39:03.621323 sshd-session[1901]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:39:03.626490 systemd-logind[1389]: New session 10 of user core. Mar 17 17:39:03.636935 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 17 17:39:03.694561 sudo[1905]: 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 Mar 17 17:39:03.694994 sudo[1905]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:39:03.700013 sudo[1905]: pam_unix(sudo:session): session closed for user root Mar 17 17:39:03.708826 sudo[1904]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Mar 17 17:39:03.709286 sudo[1904]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:39:03.730213 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 17 17:39:03.783432 augenrules[1927]: No rules Mar 17 17:39:03.785851 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 17:39:03.786217 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 17 17:39:03.788108 sudo[1904]: pam_unix(sudo:session): session closed for user root Mar 17 17:39:03.790538 sshd[1903]: Connection closed by 10.0.0.1 port 40220 Mar 17 17:39:03.791152 sshd-session[1901]: pam_unix(sshd:session): session closed for user core Mar 17 17:39:03.807402 systemd[1]: sshd@9-10.0.0.28:22-10.0.0.1:40220.service: Deactivated successfully. Mar 17 17:39:03.809709 systemd[1]: session-10.scope: Deactivated successfully. Mar 17 17:39:03.812010 systemd-logind[1389]: Session 10 logged out. Waiting for processes to exit. Mar 17 17:39:03.821111 systemd[1]: Started sshd@10-10.0.0.28:22-10.0.0.1:40236.service - OpenSSH per-connection server daemon (10.0.0.1:40236). Mar 17 17:39:03.822577 systemd-logind[1389]: Removed session 10. Mar 17 17:39:03.866013 sshd[1935]: Accepted publickey for core from 10.0.0.1 port 40236 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:39:03.866546 sshd-session[1935]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:39:03.874509 systemd-logind[1389]: New session 11 of user core. Mar 17 17:39:03.887019 systemd[1]: Started session-11.scope - Session 11 of User core. Mar 17 17:39:03.944797 sshd[1937]: Connection closed by 10.0.0.1 port 40236 Mar 17 17:39:03.945394 sshd-session[1935]: pam_unix(sshd:session): session closed for user core Mar 17 17:39:03.954309 systemd[1]: sshd@10-10.0.0.28:22-10.0.0.1:40236.service: Deactivated successfully. Mar 17 17:39:03.957119 systemd[1]: session-11.scope: Deactivated successfully. Mar 17 17:39:03.961061 systemd-logind[1389]: Session 11 logged out. Waiting for processes to exit. Mar 17 17:39:03.972176 systemd[1]: Started sshd@11-10.0.0.28:22-10.0.0.1:40244.service - OpenSSH per-connection server daemon (10.0.0.1:40244). Mar 17 17:39:03.975838 systemd-logind[1389]: Removed session 11. Mar 17 17:39:04.024591 sshd[1944]: Accepted publickey for core from 10.0.0.1 port 40244 ssh2: RSA SHA256:pvoNHoTmHcKIZ8E4rah4Xh4kuY0L81ONuagOsU2gN/o Mar 17 17:39:04.027231 sshd-session[1944]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:39:04.036620 systemd-logind[1389]: New session 12 of user core. Mar 17 17:39:04.051004 systemd[1]: Started session-12.scope - Session 12 of User core. Mar 17 17:39:04.384406 sshd[1946]: Connection closed by 10.0.0.1 port 40244 Mar 17 17:39:04.385016 sshd-session[1944]: pam_unix(sshd:session): session closed for user core Mar 17 17:39:04.390737 systemd[1]: sshd@11-10.0.0.28:22-10.0.0.1:40244.service: Deactivated successfully. Mar 17 17:39:04.393533 systemd[1]: session-12.scope: Deactivated successfully. Mar 17 17:39:04.394306 systemd-logind[1389]: Session 12 logged out. Waiting for processes to exit. Mar 17 17:39:04.395372 systemd-logind[1389]: Removed session 12. Mar 17 17:39:26.849309 update_engine[1392]: I20250317 17:39:26.848026 1392 update_attempter.cc:509] Updating boot flags... Mar 17 17:39:27.056921 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1965) Mar 17 17:39:27.111603 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 40 scanned by (udev-worker) (1965) Mar 17 17:40:20.701646 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:20.700305Z","caller":"traceutil/trace.go:171","msg":"trace[1407046285] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:209; }","duration":"143.368427ms","start":"2025-03-17T17:40:20.556914Z","end":"2025-03-17T17:40:20.700282Z","steps":["trace[1407046285] 'read index received' (duration: 67.525355ms)","trace[1407046285] 'applied index is now lower than readState.Index' (duration: 75.842451ms)"],"step_count":2} Mar 17 17:40:20.701646 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:20.700420Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.483941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} Mar 17 17:40:20.701646 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:20.700442Z","caller":"traceutil/trace.go:171","msg":"trace[720146256] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:206; }","duration":"143.544388ms","start":"2025-03-17T17:40:20.556892Z","end":"2025-03-17T17:40:20.700437Z","steps":["trace[720146256] 'agreement among raft nodes before linearized reading' (duration: 143.492016ms)"],"step_count":1} Mar 17 17:40:20.701646 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:20.700516Z","caller":"traceutil/trace.go:171","msg":"trace[722202684] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"160.918824ms","start":"2025-03-17T17:40:20.539589Z","end":"2025-03-17T17:40:20.700508Z","steps":["trace[722202684] 'process raft request' (duration: 84.674144ms)","trace[722202684] 'compare' (duration: 75.801993ms)"],"step_count":2} Mar 17 17:40:20.702456 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:20.700607Z","caller":"traceutil/trace.go:171","msg":"trace[46652140] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"145.667821ms","start":"2025-03-17T17:40:20.554933Z","end":"2025-03-17T17:40:20.700601Z","steps":["trace[46652140] 'process raft request' (duration: 145.256463ms)"],"step_count":1} Mar 17 17:40:20.702456 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:20.700732Z","caller":"traceutil/trace.go:171","msg":"trace[1669928246] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"145.61613ms","start":"2025-03-17T17:40:20.555108Z","end":"2025-03-17T17:40:20.700724Z","steps":["trace[1669928246] 'process raft request' (duration: 145.111162ms)"],"step_count":1} Mar 17 17:40:22.293508 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:22.286513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.003637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 17 17:40:22.293508 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.286640Z","caller":"traceutil/trace.go:171","msg":"trace[1453444735] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"219.70456ms","start":"2025-03-17T17:40:22.066920Z","end":"2025-03-17T17:40:22.286625Z","steps":["trace[1453444735] 'process raft request' (duration: 81.19242ms)","trace[1453444735] 'compare' (duration: 137.876421ms)"],"step_count":2} Mar 17 17:40:22.304273 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.303127Z","caller":"traceutil/trace.go:171","msg":"trace[246434834] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:256; }","duration":"189.567619ms","start":"2025-03-17T17:40:22.113542Z","end":"2025-03-17T17:40:22.303109Z","steps":["trace[246434834] 'read index received' (duration: 34.579939ms)","trace[246434834] 'applied index is now lower than readState.Index' (duration: 154.986698ms)"],"step_count":2} Mar 17 17:40:22.304273 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:22.303232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.674557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} Mar 17 17:40:22.304273 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.303257Z","caller":"traceutil/trace.go:171","msg":"trace[828865531] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:251; }","duration":"189.74403ms","start":"2025-03-17T17:40:22.113506Z","end":"2025-03-17T17:40:22.303250Z","steps":["trace[828865531] 'agreement among raft nodes before linearized reading' (duration: 189.672773ms)"],"step_count":1} Mar 17 17:40:22.304273 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.303321Z","caller":"traceutil/trace.go:171","msg":"trace[1692987472] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"201.335088ms","start":"2025-03-17T17:40:22.101979Z","end":"2025-03-17T17:40:22.303314Z","steps":["trace[1692987472] 'process raft request' (duration: 201.030339ms)"],"step_count":1} Mar 17 17:40:22.304273 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.303360Z","caller":"traceutil/trace.go:171","msg":"trace[1136381897] transaction","detail":"{read_only:false; number_of_response:0; response_revision:251; }","duration":"199.197235ms","start":"2025-03-17T17:40:22.104158Z","end":"2025-03-17T17:40:22.303355Z","steps":["trace[1136381897] 'process raft request' (duration: 198.924959ms)"],"step_count":1} Mar 17 17:40:22.520518 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.519936Z","caller":"traceutil/trace.go:171","msg":"trace[13194537] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:260; }","duration":"186.063204ms","start":"2025-03-17T17:40:22.333849Z","end":"2025-03-17T17:40:22.519912Z","steps":["trace[13194537] 'read index received' (duration: 86.513076ms)","trace[13194537] 'applied index is now lower than readState.Index' (duration: 99.549237ms)"],"step_count":2} Mar 17 17:40:22.520518 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.520000Z","caller":"traceutil/trace.go:171","msg":"trace[1456182560] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"209.707162ms","start":"2025-03-17T17:40:22.310278Z","end":"2025-03-17T17:40:22.519985Z","steps":["trace[1456182560] 'process raft request' (duration: 110.322935ms)","trace[1456182560] 'compare' (duration: 99.115829ms)"],"step_count":2} Mar 17 17:40:22.520518 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.520035Z","caller":"traceutil/trace.go:171","msg":"trace[836725664] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"206.562113ms","start":"2025-03-17T17:40:22.313465Z","end":"2025-03-17T17:40:22.520027Z","steps":["trace[836725664] 'process raft request' (duration: 206.388588ms)"],"step_count":1} Mar 17 17:40:22.520518 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:22.520112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.771646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} Mar 17 17:40:22.520811 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.520134Z","caller":"traceutil/trace.go:171","msg":"trace[1609183237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:254; }","duration":"196.838997ms","start":"2025-03-17T17:40:22.323288Z","end":"2025-03-17T17:40:22.520127Z","steps":["trace[1609183237] 'agreement among raft nodes before linearized reading' (duration: 196.777187ms)"],"step_count":1} Mar 17 17:40:22.552669 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.529267Z","caller":"traceutil/trace.go:171","msg":"trace[1579670461] transaction","detail":"{read_only:false; number_of_response:0; response_revision:256; }","duration":"180.922606ms","start":"2025-03-17T17:40:22.348329Z","end":"2025-03-17T17:40:22.529251Z","steps":["trace[1579670461] 'process raft request' (duration: 180.900192ms)"],"step_count":1} Mar 17 17:40:22.552669 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.529546Z","caller":"traceutil/trace.go:171","msg":"trace[148545118] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"182.262877ms","start":"2025-03-17T17:40:22.347272Z","end":"2025-03-17T17:40:22.529535Z","steps":["trace[148545118] 'process raft request' (duration: 181.888724ms)"],"step_count":1} Mar 17 17:40:22.552669 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.529590Z","caller":"traceutil/trace.go:171","msg":"trace[315313579] transaction","detail":"{read_only:false; number_of_response:0; response_revision:254; }","duration":"194.957899ms","start":"2025-03-17T17:40:22.334628Z","end":"2025-03-17T17:40:22.529586Z","steps":["trace[315313579] 'process raft request' (duration: 194.47194ms)"],"step_count":1} Mar 17 17:40:22.552669 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.529721Z","caller":"traceutil/trace.go:171","msg":"trace[1786499825] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"182.3933ms","start":"2025-03-17T17:40:22.347322Z","end":"2025-03-17T17:40:22.529715Z","steps":["trace[1786499825] 'process raft request' (duration: 181.885337ms)"],"step_count":1} Mar 17 17:40:22.552669 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:22.529814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.038673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" ","response":"range_response_count:1 size:3409"} Mar 17 17:40:22.552925 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:22.539348Z","caller":"traceutil/trace.go:171","msg":"trace[487469791] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:256; }","duration":"191.592082ms","start":"2025-03-17T17:40:22.347735Z","end":"2025-03-17T17:40:22.539327Z","steps":["trace[487469791] 'agreement among raft nodes before linearized reading' (duration: 182.021039ms)"],"step_count":1} Mar 17 17:40:24.908090 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:24.907022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.552393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 17 17:40:24.908090 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:24.907192Z","caller":"traceutil/trace.go:171","msg":"trace[992529153] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"176.676616ms","start":"2025-03-17T17:40:24.730503Z","end":"2025-03-17T17:40:24.907180Z","steps":["trace[992529153] 'process raft request' (duration: 176.622291ms)"],"step_count":1} Mar 17 17:40:24.908090 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:24.907307Z","caller":"traceutil/trace.go:171","msg":"trace[883921800] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"253.45496ms","start":"2025-03-17T17:40:24.653804Z","end":"2025-03-17T17:40:24.907259Z","steps":["trace[883921800] 'process raft request' (duration: 119.611756ms)","trace[883921800] 'compare' (duration: 133.398236ms)"],"step_count":2} Mar 17 17:40:25.163191 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:25.161384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.116338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 17 17:40:25.163191 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:25.161483Z","caller":"traceutil/trace.go:171","msg":"trace[1767883370] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"151.988128ms","start":"2025-03-17T17:40:25.009481Z","end":"2025-03-17T17:40:25.161469Z","steps":["trace[1767883370] 'process raft request' (duration: 50.733794ms)","trace[1767883370] 'compare' (duration: 100.989253ms)"],"step_count":2} Mar 17 17:40:34.941671 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:34.941193Z","caller":"traceutil/trace.go:171","msg":"trace[1713631246] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"145.485062ms","start":"2025-03-17T17:40:34.795679Z","end":"2025-03-17T17:40:34.941164Z","steps":["trace[1713631246] 'process raft request' (duration: 46.936399ms)","trace[1713631246] 'compare' (duration: 98.408093ms)"],"step_count":2} Mar 17 17:40:34.950094 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:34.949649Z","caller":"traceutil/trace.go:171","msg":"trace[1305071207] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"139.445141ms","start":"2025-03-17T17:40:34.810173Z","end":"2025-03-17T17:40:34.949618Z","steps":["trace[1305071207] 'process raft request' (duration: 139.237695ms)"],"step_count":1} Mar 17 17:40:34.950094 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:34.949664Z","caller":"traceutil/trace.go:171","msg":"trace[1975488400] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:366; }","duration":"134.773266ms","start":"2025-03-17T17:40:34.814872Z","end":"2025-03-17T17:40:34.949645Z","steps":["trace[1975488400] 'read index received' (duration: 27.838196ms)","trace[1975488400] 'applied index is now lower than readState.Index' (duration: 106.934299ms)"],"step_count":2} Mar 17 17:40:34.950518 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:34.949921Z","caller":"traceutil/trace.go:171","msg":"trace[1063494957] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"136.036638ms","start":"2025-03-17T17:40:34.813869Z","end":"2025-03-17T17:40:34.949905Z","steps":["trace[1063494957] 'process raft request' (duration: 135.738827ms)"],"step_count":1} Mar 17 17:40:34.950518 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:34.949954Z","caller":"traceutil/trace.go:171","msg":"trace[2039207929] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"139.246051ms","start":"2025-03-17T17:40:34.810702Z","end":"2025-03-17T17:40:34.949948Z","steps":["trace[2039207929] 'process raft request' (duration: 138.817239ms)"],"step_count":1} Mar 17 17:40:34.950518 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:34.949965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.079072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} Mar 17 17:40:34.950518 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:34.950001Z","caller":"traceutil/trace.go:171","msg":"trace[745859019] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:358; }","duration":"135.138987ms","start":"2025-03-17T17:40:34.814850Z","end":"2025-03-17T17:40:34.949989Z","steps":["trace[745859019] 'agreement among raft nodes before linearized reading' (duration: 135.075054ms)"],"step_count":1} Mar 17 17:40:34.988017 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:34.987652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.224681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} Mar 17 17:40:34.988017 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:34.987704Z","caller":"traceutil/trace.go:171","msg":"trace[1341297963] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:360; }","duration":"122.31358ms","start":"2025-03-17T17:40:34.865378Z","end":"2025-03-17T17:40:34.987692Z","steps":["trace[1341297963] 'agreement among raft nodes before linearized reading' (duration: 122.241251ms)"],"step_count":1} Mar 17 17:40:35.386178 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.385406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.443074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Mar 17 17:40:35.386423 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.385648Z","caller":"traceutil/trace.go:171","msg":"trace[564539062] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:379; }","duration":"345.554637ms","start":"2025-03-17T17:40:35.040080Z","end":"2025-03-17T17:40:35.385635Z","steps":["trace[564539062] 'read index received' (duration: 192.803453ms)","trace[564539062] 'applied index is now lower than readState.Index' (duration: 152.750193ms)"],"step_count":2} Mar 17 17:40:35.386423 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.385651Z","caller":"traceutil/trace.go:171","msg":"trace[933811409] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"357.11201ms","start":"2025-03-17T17:40:35.028503Z","end":"2025-03-17T17:40:35.385615Z","steps":["trace[933811409] 'process raft request' (duration: 204.390783ms)","trace[933811409] 'compare' (duration: 152.308176ms)"],"step_count":2} Mar 17 17:40:35.386423 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.385712Z","caller":"traceutil/trace.go:171","msg":"trace[1026260285] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"354.222705ms","start":"2025-03-17T17:40:35.031483Z","end":"2025-03-17T17:40:35.385705Z","steps":["trace[1026260285] 'process raft request' (duration: 354.01158ms)"],"step_count":1} Mar 17 17:40:35.386423 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.385806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.028495Z","time spent":"357.231641ms","remote":"10.0.0.34:53456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 17 17:40:35.386648 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.385922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.031473Z","time spent":"354.259385ms","remote":"10.0.0.34:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3506,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 17 17:40:35.386648 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.385989Z","caller":"traceutil/trace.go:171","msg":"trace[1238248787] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"354.083498ms","start":"2025-03-17T17:40:35.031893Z","end":"2025-03-17T17:40:35.385977Z","steps":["trace[1238248787] 'process raft request' (duration: 353.646771ms)"],"step_count":1} Mar 17 17:40:35.386648 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.385957Z","caller":"traceutil/trace.go:171","msg":"trace[76980965] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"353.890648ms","start":"2025-03-17T17:40:35.032038Z","end":"2025-03-17T17:40:35.385928Z","steps":["trace[76980965] 'process raft request' (duration: 353.56328ms)"],"step_count":1} Mar 17 17:40:35.386648 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.385981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.701201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} Mar 17 17:40:35.386808 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.386034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.031885Z","time spent":"354.128575ms","remote":"10.0.0.34:53456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 17 17:40:35.386808 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.386046Z","caller":"traceutil/trace.go:171","msg":"trace[1544921772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:371; }","duration":"357.798947ms","start":"2025-03-17T17:40:35.028230Z","end":"2025-03-17T17:40:35.386029Z","steps":["trace[1544921772] 'agreement among raft nodes before linearized reading' (duration: 357.695199ms)"],"step_count":1} Mar 17 17:40:35.386808 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.386079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.028218Z","time spent":"357.851999ms","remote":"10.0.0.34:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} Mar 17 17:40:35.386944 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.386219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.032014Z","time spent":"354.072366ms","remote":"10.0.0.34:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 17 17:40:35.450622 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.450176Z","caller":"traceutil/trace.go:171","msg":"trace[1664802018] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"409.662995ms","start":"2025-03-17T17:40:35.040497Z","end":"2025-03-17T17:40:35.450160Z","steps":["trace[1664802018] 'process raft request' (duration: 409.563975ms)"],"step_count":1} Mar 17 17:40:35.450622 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.450242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.762667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} Mar 17 17:40:35.450622 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.450274Z","caller":"traceutil/trace.go:171","msg":"trace[188862980] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:372; }","duration":"310.828443ms","start":"2025-03-17T17:40:35.139438Z","end":"2025-03-17T17:40:35.450266Z","steps":["trace[188862980] 'agreement among raft nodes before linearized reading' (duration: 310.735785ms)"],"step_count":1} Mar 17 17:40:35.450622 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.450278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.040481Z","time spent":"409.750494ms","remote":"10.0.0.34:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15846,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 17 17:40:35.450970 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.450297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.139429Z","time spent":"310.861226ms","remote":"10.0.0.34:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} Mar 17 17:40:35.450970 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.450335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.880704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:177"} Mar 17 17:40:35.450970 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.450351Z","caller":"traceutil/trace.go:171","msg":"trace[2111650135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:372; }","duration":"310.924918ms","start":"2025-03-17T17:40:35.139422Z","end":"2025-03-17T17:40:35.450347Z","steps":["trace[2111650135] 'agreement among raft nodes before linearized reading' (duration: 310.895242ms)"],"step_count":1} Mar 17 17:40:35.450970 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.450360Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.022443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2879"} Mar 17 17:40:35.450970 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.450363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.139403Z","time spent":"310.956909ms","remote":"10.0.0.34:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":201,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} Mar 17 17:40:35.451151 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.450377Z","caller":"traceutil/trace.go:171","msg":"trace[328244848] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:372; }","duration":"408.06782ms","start":"2025-03-17T17:40:35.042304Z","end":"2025-03-17T17:40:35.450371Z","steps":["trace[328244848] 'agreement among raft nodes before linearized reading' (duration: 408.03669ms)"],"step_count":1} Mar 17 17:40:35.451151 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.450394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.042284Z","time spent":"408.10456ms","remote":"10.0.0.34:53832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2903,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} Mar 17 17:40:35.576679 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.575975Z","caller":"traceutil/trace.go:171","msg":"trace[1145163948] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"125.803014ms","start":"2025-03-17T17:40:35.450156Z","end":"2025-03-17T17:40:35.575959Z","steps":["trace[1145163948] 'read index received' (duration: 125.662515ms)","trace[1145163948] 'applied index is now lower than readState.Index' (duration: 139.988µs)"],"step_count":2} Mar 17 17:40:35.576679 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.576056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.517348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} Mar 17 17:40:35.576679 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576092Z","caller":"traceutil/trace.go:171","msg":"trace[324610553] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:373; }","duration":"179.603092ms","start":"2025-03-17T17:40:35.396480Z","end":"2025-03-17T17:40:35.576083Z","steps":["trace[324610553] 'agreement among raft nodes before linearized reading' (duration: 179.499463ms)"],"step_count":1} Mar 17 17:40:35.576679 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576090Z","caller":"traceutil/trace.go:171","msg":"trace[1969690474] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"185.742134ms","start":"2025-03-17T17:40:35.390316Z","end":"2025-03-17T17:40:35.576058Z","steps":["trace[1969690474] 'process raft request' (duration: 185.549555ms)"],"step_count":1} Mar 17 17:40:35.576679 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.576108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.650053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:3521"} Mar 17 17:40:35.577031 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.576169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rtf58\" ","response":"range_response_count:1 size:15861"} Mar 17 17:40:35.577031 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576192Z","caller":"traceutil/trace.go:171","msg":"trace[1486703965] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rtf58; range_end:; response_count:1; response_revision:373; }","duration":"124.841842ms","start":"2025-03-17T17:40:35.451344Z","end":"2025-03-17T17:40:35.576186Z","steps":["trace[1486703965] 'agreement among raft nodes before linearized reading' (duration: 124.739987ms)"],"step_count":1} Mar 17 17:40:35.577031 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576203Z","caller":"traceutil/trace.go:171","msg":"trace[618712184] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:373; }","duration":"179.794178ms","start":"2025-03-17T17:40:35.396393Z","end":"2025-03-17T17:40:35.576187Z","steps":["trace[618712184] 'agreement among raft nodes before linearized reading' (duration: 179.62309ms)"],"step_count":1} Mar 17 17:40:35.577031 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.576070Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.709572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} Mar 17 17:40:35.577031 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576262Z","caller":"traceutil/trace.go:171","msg":"trace[983889844] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:373; }","duration":"185.935134ms","start":"2025-03-17T17:40:35.390320Z","end":"2025-03-17T17:40:35.576255Z","steps":["trace[983889844] 'agreement among raft nodes before linearized reading' (duration: 185.726974ms)"],"step_count":1} Mar 17 17:40:35.577233 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.576293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.060262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-q7rsd\" ","response":"range_response_count:1 size:3419"} Mar 17 17:40:35.577233 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576308Z","caller":"traceutil/trace.go:171","msg":"trace[784853305] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-q7rsd; range_end:; response_count:1; response_revision:373; }","duration":"122.107824ms","start":"2025-03-17T17:40:35.454196Z","end":"2025-03-17T17:40:35.576303Z","steps":["trace[784853305] 'agreement among raft nodes before linearized reading' (duration: 122.07994ms)"],"step_count":1} Mar 17 17:40:35.577233 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.576327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.013894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2879"} Mar 17 17:40:35.577233 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.576336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.916224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-q7rsd\" ","response":"range_response_count:1 size:3419"} Mar 17 17:40:35.577233 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576358Z","caller":"traceutil/trace.go:171","msg":"trace[469407402] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:373; }","duration":"122.057868ms","start":"2025-03-17T17:40:35.454291Z","end":"2025-03-17T17:40:35.576349Z","steps":["trace[469407402] 'agreement among raft nodes before linearized reading' (duration: 121.971723ms)"],"step_count":1} Mar 17 17:40:35.577391 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.576361Z","caller":"traceutil/trace.go:171","msg":"trace[1948380340] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-q7rsd; range_end:; response_count:1; response_revision:373; }","duration":"124.941342ms","start":"2025-03-17T17:40:35.451413Z","end":"2025-03-17T17:40:35.576354Z","steps":["trace[1948380340] 'agreement among raft nodes before linearized reading' (duration: 124.899672ms)"],"step_count":1} Mar 17 17:40:35.906382 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.905696Z","caller":"traceutil/trace.go:171","msg":"trace[2068307092] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"321.862815ms","start":"2025-03-17T17:40:35.583802Z","end":"2025-03-17T17:40:35.905665Z","steps":["trace[2068307092] 'process raft request' (duration: 294.931757ms)","trace[2068307092] 'compare' (duration: 26.674537ms)"],"step_count":2} Mar 17 17:40:35.906382 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.905724Z","caller":"traceutil/trace.go:171","msg":"trace[467146794] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"321.861391ms","start":"2025-03-17T17:40:35.583852Z","end":"2025-03-17T17:40:35.905713Z","steps":["trace[467146794] 'process raft request' (duration: 321.68847ms)"],"step_count":1} Mar 17 17:40:35.906382 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.905777Z","caller":"traceutil/trace.go:171","msg":"trace[254848445] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"321.448041ms","start":"2025-03-17T17:40:35.584322Z","end":"2025-03-17T17:40:35.905770Z","steps":["trace[254848445] 'process raft request' (duration: 321.27553ms)"],"step_count":1} Mar 17 17:40:35.906382 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.905827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.583773Z","time spent":"321.993446ms","remote":"10.0.0.34:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 17 17:40:35.906714 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.905840Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.584312Z","time spent":"321.483949ms","remote":"10.0.0.34:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3980,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 17 17:40:35.906714 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.905871Z","caller":"traceutil/trace.go:171","msg":"trace[1352955378] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"307.228727ms","start":"2025-03-17T17:40:35.598634Z","end":"2025-03-17T17:40:35.905862Z","steps":["trace[1352955378] 'process raft request' (duration: 307.038503ms)"],"step_count":1} Mar 17 17:40:35.906714 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.905896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.598611Z","time spent":"307.272971ms","remote":"10.0.0.34:53456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":785,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 17 17:40:35.906820 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.905825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.583824Z","time spent":"321.949101ms","remote":"10.0.0.34:53568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3154,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Mar 17 17:40:35.906820 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:35.905720Z","caller":"traceutil/trace.go:171","msg":"trace[857205250] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"320.810759ms","start":"2025-03-17T17:40:35.584892Z","end":"2025-03-17T17:40:35.905702Z","steps":["trace[857205250] 'process raft request' (duration: 320.752628ms)"],"step_count":1} Mar 17 17:40:35.906820 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:35.905951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-03-17T17:40:35.584882Z","time spent":"321.045138ms","remote":"10.0.0.34:53826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Mar 17 17:40:36.227371 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:36.226948Z","caller":"traceutil/trace.go:171","msg":"trace[594815523] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"103.670889ms","start":"2025-03-17T17:40:36.123254Z","end":"2025-03-17T17:40:36.226925Z","steps":["trace[594815523] 'process raft request' (duration: 42.555631ms)","trace[594815523] 'compare' (duration: 61.021991ms)"],"step_count":2} Mar 17 17:40:43.243782 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:43.243382Z","caller":"traceutil/trace.go:171","msg":"trace[1309626256] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"102.079357ms","start":"2025-03-17T17:40:43.141287Z","end":"2025-03-17T17:40:43.243366Z","steps":["trace[1309626256] 'process raft request' (duration: 101.978916ms)"],"step_count":1} Mar 17 17:40:45.378587 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:45.378240Z","caller":"traceutil/trace.go:171","msg":"trace[420418860] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"131.397245ms","start":"2025-03-17T17:40:45.246827Z","end":"2025-03-17T17:40:45.378224Z","steps":["trace[420418860] 'process raft request' (duration: 131.278769ms)"],"step_count":1} Mar 17 17:40:55.634025 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:55.633707Z","caller":"traceutil/trace.go:171","msg":"trace[1766046252] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"147.46589ms","start":"2025-03-17T17:40:55.486223Z","end":"2025-03-17T17:40:55.633689Z","steps":["trace[1766046252] 'process raft request' (duration: 147.320785ms)"],"step_count":1} Mar 17 17:40:56.089672 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:56.089333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.390822ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Mar 17 17:40:56.089672 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:56.089406Z","caller":"traceutil/trace.go:171","msg":"trace[508855320] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"100.607335ms","start":"2025-03-17T17:40:55.988781Z","end":"2025-03-17T17:40:56.089389Z","steps":["trace[508855320] 'read index received' (duration: 36.089µs)","trace[508855320] 'applied index is now lower than readState.Index' (duration: 100.569904ms)"],"step_count":2} Mar 17 17:40:56.089824 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:40:56.089488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.712514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Mar 17 17:40:56.089824 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:56.089512Z","caller":"traceutil/trace.go:171","msg":"trace[1113706519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"100.769142ms","start":"2025-03-17T17:40:55.988735Z","end":"2025-03-17T17:40:56.089504Z","steps":["trace[1113706519] 'agreement among raft nodes before linearized reading' (duration: 100.720921ms)"],"step_count":1} Mar 17 17:40:57.545486 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:57.545088Z","caller":"traceutil/trace.go:171","msg":"trace[39600998] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"198.854425ms","start":"2025-03-17T17:40:57.346215Z","end":"2025-03-17T17:40:57.545069Z","steps":["trace[39600998] 'process raft request' (duration: 101.427009ms)","trace[39600998] 'compare' (duration: 97.294583ms)"],"step_count":2} Mar 17 17:40:58.463587 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:58.462941Z","caller":"traceutil/trace.go:171","msg":"trace[1534826537] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"119.113286ms","start":"2025-03-17T17:40:58.343810Z","end":"2025-03-17T17:40:58.462923Z","steps":["trace[1534826537] 'process raft request' (duration: 117.948384ms)"],"step_count":1} Mar 17 17:40:59.568504 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:59.568121Z","caller":"traceutil/trace.go:171","msg":"trace[1709226363] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"207.291034ms","start":"2025-03-17T17:40:59.360802Z","end":"2025-03-17T17:40:59.568093Z","steps":["trace[1709226363] 'process raft request' (duration: 180.991499ms)","trace[1709226363] 'compare' (duration: 26.200948ms)"],"step_count":2} Mar 17 17:40:59.576826 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:40:59.576606Z","caller":"traceutil/trace.go:171","msg":"trace[1951736174] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"173.372617ms","start":"2025-03-17T17:40:59.403220Z","end":"2025-03-17T17:40:59.576592Z","steps":["trace[1951736174] 'process raft request' (duration: 173.277035ms)"],"step_count":1} Mar 17 17:41:00.562853 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:00.562367Z","caller":"traceutil/trace.go:171","msg":"trace[857650148] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"204.672422ms","start":"2025-03-17T17:41:00.357670Z","end":"2025-03-17T17:41:00.562342Z","steps":["trace[857650148] 'process raft request' (duration: 204.52337ms)"],"step_count":1} Mar 17 17:41:00.569511 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:00.569058Z","caller":"traceutil/trace.go:171","msg":"trace[323341701] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"192.636895ms","start":"2025-03-17T17:41:00.376391Z","end":"2025-03-17T17:41:00.569028Z","steps":["trace[323341701] 'process raft request' (duration: 192.486238ms)"],"step_count":1} Mar 17 17:41:01.964577 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:01.964296Z","caller":"traceutil/trace.go:171","msg":"trace[162869983] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"100.220668ms","start":"2025-03-17T17:41:01.864056Z","end":"2025-03-17T17:41:01.964277Z","steps":["trace[162869983] 'process raft request' (duration: 64.775078ms)","trace[162869983] 'compare' (duration: 35.303291ms)"],"step_count":2} Mar 17 17:41:03.506834 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:03.506502Z","caller":"traceutil/trace.go:171","msg":"trace[2083639132] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"212.036211ms","start":"2025-03-17T17:41:03.294451Z","end":"2025-03-17T17:41:03.506487Z","steps":["trace[2083639132] 'process raft request' (duration: 205.628061ms)"],"step_count":1} Mar 17 17:41:03.510519 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:03.510317Z","caller":"traceutil/trace.go:171","msg":"trace[1026175368] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"117.361148ms","start":"2025-03-17T17:41:03.392947Z","end":"2025-03-17T17:41:03.510308Z","steps":["trace[1026175368] 'process raft request' (duration: 117.318026ms)"],"step_count":1} Mar 17 17:41:08.309915 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:08.309436Z","caller":"traceutil/trace.go:171","msg":"trace[259753627] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"110.515033ms","start":"2025-03-17T17:41:08.198905Z","end":"2025-03-17T17:41:08.309421Z","steps":["trace[259753627] 'process raft request' (duration: 110.390266ms)"],"step_count":1} Mar 17 17:41:08.527123 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:41:08.526823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.371381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Mar 17 17:41:08.527123 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:08.526907Z","caller":"traceutil/trace.go:171","msg":"trace[1871872436] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"158.734785ms","start":"2025-03-17T17:41:08.368159Z","end":"2025-03-17T17:41:08.526894Z","steps":["trace[1871872436] 'process raft request' (duration: 36.232626ms)","trace[1871872436] 'compare' (duration: 122.269909ms)"],"step_count":2} Mar 17 17:41:08.528969 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:08.528620Z","caller":"traceutil/trace.go:171","msg":"trace[1644614696] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"160.02138ms","start":"2025-03-17T17:41:08.368590Z","end":"2025-03-17T17:41:08.528612Z","steps":["trace[1644614696] 'process raft request' (duration: 159.838545ms)"],"step_count":1} Mar 17 17:41:08.529108 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:08.528901Z","caller":"traceutil/trace.go:171","msg":"trace[1440204888] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"157.625393ms","start":"2025-03-17T17:41:08.371265Z","end":"2025-03-17T17:41:08.528890Z","steps":["trace[1440204888] 'process raft request' (duration: 157.293363ms)"],"step_count":1} Mar 17 17:41:08.529343 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:08.529093Z","caller":"traceutil/trace.go:171","msg":"trace[1235487992] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"150.961251ms","start":"2025-03-17T17:41:08.378123Z","end":"2025-03-17T17:41:08.529084Z","steps":["trace[1235487992] 'process raft request' (duration: 150.900175ms)"],"step_count":1} Mar 17 17:41:08.971772 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:41:08.971369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.778097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Mar 17 17:41:08.971994 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:08.971636Z","caller":"traceutil/trace.go:171","msg":"trace[1753384714] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"171.22415ms","start":"2025-03-17T17:41:08.800329Z","end":"2025-03-17T17:41:08.971553Z","steps":["trace[1753384714] 'process raft request' (duration: 61.205868ms)","trace[1753384714] 'compare' (duration: 109.66355ms)"],"step_count":2} Mar 17 17:41:14.042656 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.042304Z","caller":"traceutil/trace.go:171","msg":"trace[1564896507] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"144.642107ms","start":"2025-03-17T17:41:13.897638Z","end":"2025-03-17T17:41:14.042280Z","steps":["trace[1564896507] 'process raft request' (duration: 107.695814ms)","trace[1564896507] 'compare' (duration: 36.841535ms)"],"step_count":2} Mar 17 17:41:14.311125 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.310634Z","caller":"traceutil/trace.go:171","msg":"trace[1405219796] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"210.401571ms","start":"2025-03-17T17:41:14.100212Z","end":"2025-03-17T17:41:14.310613Z","steps":["trace[1405219796] 'read index received' (duration: 152.880892ms)","trace[1405219796] 'applied index is now lower than readState.Index' (duration: 57.520018ms)"],"step_count":2} Mar 17 17:41:14.311252 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.310740Z","caller":"traceutil/trace.go:171","msg":"trace[1303061123] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"255.570141ms","start":"2025-03-17T17:41:14.055128Z","end":"2025-03-17T17:41:14.310698Z","steps":["trace[1303061123] 'process raft request' (duration: 197.956556ms)","trace[1303061123] 'compare' (duration: 57.406533ms)"],"step_count":2} Mar 17 17:41:14.311252 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:41:14.310792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.563519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} Mar 17 17:41:14.311252 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.310820Z","caller":"traceutil/trace.go:171","msg":"trace[437428368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:573; }","duration":"210.649672ms","start":"2025-03-17T17:41:14.100162Z","end":"2025-03-17T17:41:14.310812Z","steps":["trace[437428368] 'agreement among raft nodes before linearized reading' (duration: 210.540274ms)"],"step_count":1} Mar 17 17:41:14.376752 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.376343Z","caller":"traceutil/trace.go:171","msg":"trace[273779560] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"171.516099ms","start":"2025-03-17T17:41:14.204801Z","end":"2025-03-17T17:41:14.376317Z","steps":["trace[273779560] 'process raft request' (duration: 171.350415ms)"],"step_count":1} Mar 17 17:41:14.376904 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.376436Z","caller":"traceutil/trace.go:171","msg":"trace[1829112440] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"159.352045ms","start":"2025-03-17T17:41:14.217059Z","end":"2025-03-17T17:41:14.376411Z","steps":["trace[1829112440] 'process raft request' (duration: 159.213462ms)"],"step_count":1} Mar 17 17:41:14.463246 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.462799Z","caller":"traceutil/trace.go:171","msg":"trace[960919311] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"143.247456ms","start":"2025-03-17T17:41:14.319534Z","end":"2025-03-17T17:41:14.462782Z","steps":["trace[960919311] 'process raft request' (duration: 119.757272ms)","trace[960919311] 'compare' (duration: 23.398691ms)"],"step_count":2} Mar 17 17:41:14.463246 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.462994Z","caller":"traceutil/trace.go:171","msg":"trace[2059230255] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"142.940664ms","start":"2025-03-17T17:41:14.320029Z","end":"2025-03-17T17:41:14.462970Z","steps":["trace[2059230255] 'process raft request' (duration: 142.84859ms)"],"step_count":1} Mar 17 17:41:14.463458 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:14.463154Z","caller":"traceutil/trace.go:171","msg":"trace[619078137] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"140.80302ms","start":"2025-03-17T17:41:14.322342Z","end":"2025-03-17T17:41:14.463145Z","steps":["trace[619078137] 'process raft request' (duration: 140.57578ms)"],"step_count":1} Mar 17 17:41:16.798034 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:16.797642Z","caller":"traceutil/trace.go:171","msg":"trace[1201248385] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"154.761196ms","start":"2025-03-17T17:41:16.642862Z","end":"2025-03-17T17:41:16.797623Z","steps":["trace[1201248385] 'process raft request' (duration: 62.020686ms)","trace[1201248385] 'compare' (duration: 92.603682ms)"],"step_count":2} Mar 17 17:41:23.752624 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:23.752220Z","caller":"traceutil/trace.go:171","msg":"trace[1204919052] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"114.280733ms","start":"2025-03-17T17:41:23.637919Z","end":"2025-03-17T17:41:23.752200Z","steps":["trace[1204919052] 'process raft request' (duration: 30.282061ms)","trace[1204919052] 'compare' (duration: 83.905997ms)"],"step_count":2} Mar 17 17:41:24.110010 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:24.109577Z","caller":"traceutil/trace.go:171","msg":"trace[1283174907] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"130.922634ms","start":"2025-03-17T17:41:23.978624Z","end":"2025-03-17T17:41:24.109547Z","steps":["trace[1283174907] 'process raft request' (duration: 57.768671ms)","trace[1283174907] 'compare' (duration: 73.004782ms)"],"step_count":2} Mar 17 17:41:24.110010 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:24.109626Z","caller":"traceutil/trace.go:171","msg":"trace[412607584] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"105.848595ms","start":"2025-03-17T17:41:24.003766Z","end":"2025-03-17T17:41:24.109615Z","steps":["trace[412607584] 'process raft request' (duration: 105.721936ms)"],"step_count":1} Mar 17 17:41:24.371198 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:24.370736Z","caller":"traceutil/trace.go:171","msg":"trace[1509425074] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"298.820857ms","start":"2025-03-17T17:41:24.071896Z","end":"2025-03-17T17:41:24.370717Z","steps":["trace[1509425074] 'process raft request' (duration: 207.822369ms)","trace[1509425074] 'compare' (duration: 90.890383ms)"],"step_count":2} Mar 17 17:41:24.413215 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:24.412858Z","caller":"traceutil/trace.go:171","msg":"trace[454622553] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"276.837764ms","start":"2025-03-17T17:41:24.136001Z","end":"2025-03-17T17:41:24.412839Z","steps":["trace[454622553] 'process raft request' (duration: 276.71957ms)"],"step_count":1} Mar 17 17:41:25.430267 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.429874Z","caller":"traceutil/trace.go:171","msg":"trace[530101485] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"117.304344ms","start":"2025-03-17T17:41:25.312550Z","end":"2025-03-17T17:41:25.429855Z","steps":["trace[530101485] 'read index received' (duration: 14.59398ms)","trace[530101485] 'applied index is now lower than readState.Index' (duration: 102.709833ms)"],"step_count":2} Mar 17 17:41:25.430267 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.429932Z","caller":"traceutil/trace.go:171","msg":"trace[282834379] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"117.429421ms","start":"2025-03-17T17:41:25.312489Z","end":"2025-03-17T17:41:25.429919Z","steps":["trace[282834379] 'process raft request' (duration: 79.95668ms)","trace[282834379] 'compare' (duration: 37.333287ms)"],"step_count":2} Mar 17 17:41:25.430267 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:41:25.430028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.432456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/default/\" range_end:\"/registry/limitranges/default0\" ","response":"range_response_count:0 size:5"} Mar 17 17:41:25.430267 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.430102Z","caller":"traceutil/trace.go:171","msg":"trace[1170590450] range","detail":"{range_begin:/registry/limitranges/default/; range_end:/registry/limitranges/default0; response_count:0; response_revision:671; }","duration":"117.541242ms","start":"2025-03-17T17:41:25.312545Z","end":"2025-03-17T17:41:25.430086Z","steps":["trace[1170590450] 'agreement among raft nodes before linearized reading' (duration: 117.394515ms)"],"step_count":1} Mar 17 17:41:25.626377 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.626066Z","caller":"traceutil/trace.go:171","msg":"trace[1292505848] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"178.835743ms","start":"2025-03-17T17:41:25.447211Z","end":"2025-03-17T17:41:25.626047Z","steps":["trace[1292505848] 'process raft request' (duration: 163.660525ms)","trace[1292505848] 'compare' (duration: 15.095367ms)"],"step_count":2} Mar 17 17:41:25.649936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.649439Z","caller":"traceutil/trace.go:171","msg":"trace[513751770] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"201.843858ms","start":"2025-03-17T17:41:25.447579Z","end":"2025-03-17T17:41:25.649423Z","steps":["trace[513751770] 'process raft request' (duration: 201.735514ms)"],"step_count":1} Mar 17 17:41:25.649936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.649510Z","caller":"traceutil/trace.go:171","msg":"trace[1505487666] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"166.113369ms","start":"2025-03-17T17:41:25.483387Z","end":"2025-03-17T17:41:25.649501Z","steps":["trace[1505487666] 'process raft request' (duration: 166.074785ms)"],"step_count":1} Mar 17 17:41:25.649936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.649540Z","caller":"traceutil/trace.go:171","msg":"trace[1394451390] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"201.346248ms","start":"2025-03-17T17:41:25.448184Z","end":"2025-03-17T17:41:25.649530Z","steps":["trace[1394451390] 'process raft request' (duration: 201.202696ms)"],"step_count":1} Mar 17 17:41:25.649936 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:25.649584Z","caller":"traceutil/trace.go:171","msg":"trace[2042911664] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"199.789457ms","start":"2025-03-17T17:41:25.449770Z","end":"2025-03-17T17:41:25.649559Z","steps":["trace[2042911664] 'process raft request' (duration: 199.648351ms)"],"step_count":1} Mar 17 17:41:52.566555 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:52.565991Z","caller":"traceutil/trace.go:171","msg":"trace[946033607] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"106.198932ms","start":"2025-03-17T17:41:52.459762Z","end":"2025-03-17T17:41:52.565961Z","steps":["trace[946033607] 'process raft request' (duration: 85.921647ms)","trace[946033607] 'compare' (duration: 20.123304ms)"],"step_count":2} Mar 17 17:41:54.314108 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:41:54.313737Z","caller":"traceutil/trace.go:171","msg":"trace[540372657] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"127.938931ms","start":"2025-03-17T17:41:54.185779Z","end":"2025-03-17T17:41:54.313718Z","steps":["trace[540372657] 'process raft request' (duration: 127.831819ms)"],"step_count":1} Mar 17 17:42:08.928830 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:42:08.928172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.119732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zrddl\" ","response":"range_response_count:1 size:16806"} Mar 17 17:42:08.935380 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:08.928349Z","caller":"traceutil/trace.go:171","msg":"trace[1519953269] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zrddl; range_end:; response_count:1; response_revision:947; }","duration":"157.357631ms","start":"2025-03-17T17:42:08.770972Z","end":"2025-03-17T17:42:08.928330Z","steps":["trace[1519953269] 'range keys from in-memory index tree' (duration: 156.989627ms)"],"step_count":1} Mar 17 17:42:08.935380 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:42:08.930813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.588924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-zrddl.182da7fa5e820554\" ","response":"range_response_count:1 size:788"} Mar 17 17:42:08.935380 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:08.930883Z","caller":"traceutil/trace.go:171","msg":"trace[464316167] range","detail":"{range_begin:/registry/events/kube-system/cilium-zrddl.182da7fa5e820554; range_end:; response_count:1; response_revision:947; }","duration":"159.675288ms","start":"2025-03-17T17:42:08.771191Z","end":"2025-03-17T17:42:08.930866Z","steps":["trace[464316167] 'range keys from in-memory index tree' (duration: 159.47492ms)"],"step_count":1} Mar 17 17:42:08.935380 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:42:08.931097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.195651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" ","response":"range_response_count:10 size:74331"} Mar 17 17:42:08.935380 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:08.931120Z","caller":"traceutil/trace.go:171","msg":"trace[1581227907] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:947; }","duration":"138.248289ms","start":"2025-03-17T17:42:08.792865Z","end":"2025-03-17T17:42:08.931114Z","steps":["trace[1581227907] 'range keys from in-memory index tree' (duration: 138.0416ms)"],"step_count":1} Mar 17 17:42:09.162458 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:09.161454Z","caller":"traceutil/trace.go:171","msg":"trace[1770374766] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"124.1229ms","start":"2025-03-17T17:42:09.037311Z","end":"2025-03-17T17:42:09.161434Z","steps":["trace[1770374766] 'process raft request' (duration: 124.075791ms)"],"step_count":1} Mar 17 17:42:09.162458 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:09.161558Z","caller":"traceutil/trace.go:171","msg":"trace[205356296] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"148.694872ms","start":"2025-03-17T17:42:09.012730Z","end":"2025-03-17T17:42:09.161425Z","steps":["trace[205356296] 'process raft request' (duration: 117.011352ms)","trace[205356296] 'compare' (duration: 31.520612ms)"],"step_count":2} Mar 17 17:42:09.162458 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:09.161619Z","caller":"traceutil/trace.go:171","msg":"trace[804255546] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"138.92701ms","start":"2025-03-17T17:42:09.022682Z","end":"2025-03-17T17:42:09.161609Z","steps":["trace[804255546] 'read index received' (duration: 106.953203ms)","trace[804255546] 'applied index is now lower than readState.Index' (duration: 31.973196ms)"],"step_count":2} Mar 17 17:42:09.162458 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:42:09.161831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.116937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" ","response":"range_response_count:13 size:86399"} Mar 17 17:42:09.162773 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:09.161858Z","caller":"traceutil/trace.go:171","msg":"trace[1675162780] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:13; response_revision:951; }","duration":"139.174115ms","start":"2025-03-17T17:42:09.022676Z","end":"2025-03-17T17:42:09.161850Z","steps":["trace[1675162780] 'agreement among raft nodes before linearized reading' (duration: 138.958649ms)"],"step_count":1} Mar 17 17:42:09.162773 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:42:09.161902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.055702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" ","response":"range_response_count:10 size:74459"} Mar 17 17:42:09.162773 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:09.161932Z","caller":"traceutil/trace.go:171","msg":"trace[1198910391] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:951; }","duration":"129.12411ms","start":"2025-03-17T17:42:09.032797Z","end":"2025-03-17T17:42:09.161922Z","steps":["trace[1198910391] 'agreement among raft nodes before linearized reading' (duration: 128.954942ms)"],"step_count":1} Mar 17 17:42:09.735535 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:09.735163Z","caller":"traceutil/trace.go:171","msg":"trace[265371932] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"152.739823ms","start":"2025-03-17T17:42:09.582400Z","end":"2025-03-17T17:42:09.735140Z","steps":["trace[265371932] 'process raft request' (duration: 105.552894ms)","trace[265371932] 'compare' (duration: 47.058097ms)"],"step_count":2} Mar 17 17:42:10.165163 etcd-wrapper[1711]: {"level":"warn","ts":"2025-03-17T17:42:10.164697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.829134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} Mar 17 17:42:10.165163 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:10.164760Z","caller":"traceutil/trace.go:171","msg":"trace[882811142] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:955; }","duration":"155.928251ms","start":"2025-03-17T17:42:10.008816Z","end":"2025-03-17T17:42:10.164744Z","steps":["trace[882811142] 'range keys from in-memory index tree' (duration: 155.759564ms)"],"step_count":1} Mar 17 17:42:14.928681 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:14.927711Z","caller":"traceutil/trace.go:171","msg":"trace[1360255147] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"185.709438ms","start":"2025-03-17T17:42:14.741978Z","end":"2025-03-17T17:42:14.927688Z","steps":["trace[1360255147] 'process raft request' (duration: 133.933739ms)","trace[1360255147] 'compare' (duration: 51.625606ms)"],"step_count":2} Mar 17 17:42:15.253954 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:15.253523Z","caller":"traceutil/trace.go:171","msg":"trace[930035249] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"106.72931ms","start":"2025-03-17T17:42:15.146771Z","end":"2025-03-17T17:42:15.253500Z","steps":["trace[930035249] 'process raft request' (duration: 106.576753ms)"],"step_count":1} Mar 17 17:42:15.345785 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:15.345340Z","caller":"traceutil/trace.go:171","msg":"trace[1163186173] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"197.557728ms","start":"2025-03-17T17:42:15.147759Z","end":"2025-03-17T17:42:15.345317Z","steps":["trace[1163186173] 'process raft request' (duration: 187.405685ms)"],"step_count":1} Mar 17 17:42:20.630629 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:20.630234Z","caller":"traceutil/trace.go:171","msg":"trace[164423812] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"112.893136ms","start":"2025-03-17T17:42:20.517324Z","end":"2025-03-17T17:42:20.630217Z","steps":["trace[164423812] 'process raft request' (duration: 29.327206ms)","trace[164423812] 'compare' (duration: 83.437258ms)"],"step_count":2} Mar 17 17:42:20.630629 etcd-wrapper[1711]: {"level":"info","ts":"2025-03-17T17:42:20.630403Z","caller":"traceutil/trace.go:171","msg":"trace[1965364664] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"105.629434ms","start":"2025-03-17T17:42:20.524749Z","end":"2025-03-17T17:42:20.630378Z","steps":["trace[1965364664] 'process raft request' (duration: 105.44073ms)"],"step_count":1}