Sep 11 00:15:12.893983 kernel: Linux version 6.12.46-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Sep 10 22:15:45 -00 2025 Sep 11 00:15:12.894014 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=20820f07706ad5590d38fe5324b9055d59a89dc1109fdc449cad1a53209b9dbd Sep 11 00:15:12.894024 kernel: BIOS-provided physical RAM map: Sep 11 00:15:12.894031 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 11 00:15:12.894045 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 11 00:15:12.894051 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 11 00:15:12.894059 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Sep 11 00:15:12.894066 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Sep 11 00:15:12.894078 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 11 00:15:12.894097 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Sep 11 00:15:12.894104 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 11 00:15:12.894111 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 11 00:15:12.894117 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 11 00:15:12.894133 kernel: NX (Execute Disable) protection: active Sep 11 00:15:12.894153 kernel: APIC: Static calls initialized Sep 11 00:15:12.894170 kernel: SMBIOS 2.8 present. Sep 11 00:15:12.894180 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Sep 11 00:15:12.894187 kernel: DMI: Memory slots populated: 1/1 Sep 11 00:15:12.894195 kernel: Hypervisor detected: KVM Sep 11 00:15:12.894202 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 11 00:15:12.894209 kernel: kvm-clock: using sched offset of 5264324638 cycles Sep 11 00:15:12.894227 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 11 00:15:12.894243 kernel: tsc: Detected 2794.750 MHz processor Sep 11 00:15:12.894255 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 11 00:15:12.894263 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 11 00:15:12.894270 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Sep 11 00:15:12.894278 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 11 00:15:12.894286 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 11 00:15:12.894293 kernel: Using GB pages for direct mapping Sep 11 00:15:12.894301 kernel: ACPI: Early table checksum verification disabled Sep 11 00:15:12.894308 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Sep 11 00:15:12.894316 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:15:12.894326 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:15:12.894352 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:15:12.894360 kernel: ACPI: FACS 0x000000009CFE0000 000040 Sep 11 00:15:12.894368 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:15:12.894389 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:15:12.894398 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:15:12.894406 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:15:12.894414 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Sep 11 00:15:12.894428 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Sep 11 00:15:12.894436 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Sep 11 00:15:12.894444 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Sep 11 00:15:12.894452 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Sep 11 00:15:12.894459 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Sep 11 00:15:12.894467 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Sep 11 00:15:12.894477 kernel: No NUMA configuration found Sep 11 00:15:12.894485 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Sep 11 00:15:12.894492 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff] Sep 11 00:15:12.894500 kernel: Zone ranges: Sep 11 00:15:12.894508 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 11 00:15:12.894516 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Sep 11 00:15:12.894523 kernel: Normal empty Sep 11 00:15:12.894531 kernel: Device empty Sep 11 00:15:12.894539 kernel: Movable zone start for each node Sep 11 00:15:12.894546 kernel: Early memory node ranges Sep 11 00:15:12.894556 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 11 00:15:12.894564 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Sep 11 00:15:12.894572 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Sep 11 00:15:12.894579 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 11 00:15:12.894587 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 11 00:15:12.894595 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Sep 11 00:15:12.894603 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 11 00:15:12.894613 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 11 00:15:12.894621 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 11 00:15:12.894631 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 11 00:15:12.894639 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 11 00:15:12.894649 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 11 00:15:12.894656 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 11 00:15:12.894664 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 11 00:15:12.894672 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 11 00:15:12.894682 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 11 00:15:12.894690 kernel: TSC deadline timer available Sep 11 00:15:12.894697 kernel: CPU topo: Max. logical packages: 1 Sep 11 00:15:12.894707 kernel: CPU topo: Max. logical dies: 1 Sep 11 00:15:12.894715 kernel: CPU topo: Max. dies per package: 1 Sep 11 00:15:12.894723 kernel: CPU topo: Max. threads per core: 1 Sep 11 00:15:12.894736 kernel: CPU topo: Num. cores per package: 4 Sep 11 00:15:12.894746 kernel: CPU topo: Num. threads per package: 4 Sep 11 00:15:12.894754 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 11 00:15:12.894769 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 11 00:15:12.894777 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 11 00:15:12.894785 kernel: kvm-guest: setup PV sched yield Sep 11 00:15:12.894796 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Sep 11 00:15:12.894804 kernel: Booting paravirtualized kernel on KVM Sep 11 00:15:12.894812 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 11 00:15:12.894822 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 11 00:15:12.894830 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 11 00:15:12.894838 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 11 00:15:12.894845 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 11 00:15:12.894853 kernel: kvm-guest: PV spinlocks enabled Sep 11 00:15:12.894861 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 11 00:15:12.894875 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=20820f07706ad5590d38fe5324b9055d59a89dc1109fdc449cad1a53209b9dbd Sep 11 00:15:12.894883 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 11 00:15:12.894891 kernel: random: crng init done Sep 11 00:15:12.894899 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 11 00:15:12.894907 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 11 00:15:12.894915 kernel: Fallback order for Node 0: 0 Sep 11 00:15:12.894922 kernel: Built 1 zonelists, mobility grouping on. Total pages: 642938 Sep 11 00:15:12.894930 kernel: Policy zone: DMA32 Sep 11 00:15:12.894938 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 11 00:15:12.894948 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 11 00:15:12.894956 kernel: ftrace: allocating 40106 entries in 157 pages Sep 11 00:15:12.894964 kernel: ftrace: allocated 157 pages with 5 groups Sep 11 00:15:12.894971 kernel: Dynamic Preempt: voluntary Sep 11 00:15:12.894979 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 11 00:15:12.894988 kernel: rcu: RCU event tracing is enabled. Sep 11 00:15:12.894996 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 11 00:15:12.895004 kernel: Trampoline variant of Tasks RCU enabled. Sep 11 00:15:12.895014 kernel: Rude variant of Tasks RCU enabled. Sep 11 00:15:12.895024 kernel: Tracing variant of Tasks RCU enabled. Sep 11 00:15:12.895032 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 11 00:15:12.895046 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 11 00:15:12.895054 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 11 00:15:12.895062 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 11 00:15:12.895070 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 11 00:15:12.895078 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 11 00:15:12.895086 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 11 00:15:12.895106 kernel: Console: colour VGA+ 80x25 Sep 11 00:15:12.895114 kernel: printk: legacy console [ttyS0] enabled Sep 11 00:15:12.895122 kernel: ACPI: Core revision 20240827 Sep 11 00:15:12.895130 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 11 00:15:12.895141 kernel: APIC: Switch to symmetric I/O mode setup Sep 11 00:15:12.895149 kernel: x2apic enabled Sep 11 00:15:12.895156 kernel: APIC: Switched APIC routing to: physical x2apic Sep 11 00:15:12.895167 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 11 00:15:12.895175 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 11 00:15:12.895185 kernel: kvm-guest: setup PV IPIs Sep 11 00:15:12.895193 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 11 00:15:12.895202 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 11 00:15:12.895210 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Sep 11 00:15:12.895218 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 11 00:15:12.895226 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 11 00:15:12.895234 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 11 00:15:12.895243 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 11 00:15:12.895253 kernel: Spectre V2 : Mitigation: Retpolines Sep 11 00:15:12.895261 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 11 00:15:12.895269 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 11 00:15:12.895277 kernel: active return thunk: retbleed_return_thunk Sep 11 00:15:12.895285 kernel: RETBleed: Mitigation: untrained return thunk Sep 11 00:15:12.895293 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 11 00:15:12.895302 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 11 00:15:12.895310 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 11 00:15:12.895320 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Sep 11 00:15:12.895347 kernel: active return thunk: srso_return_thunk Sep 11 00:15:12.895365 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 11 00:15:12.895380 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 11 00:15:12.895396 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 11 00:15:12.895407 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 11 00:15:12.895427 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 11 00:15:12.895445 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 11 00:15:12.895453 kernel: Freeing SMP alternatives memory: 32K Sep 11 00:15:12.895464 kernel: pid_max: default: 32768 minimum: 301 Sep 11 00:15:12.895473 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 11 00:15:12.895480 kernel: landlock: Up and running. Sep 11 00:15:12.895489 kernel: SELinux: Initializing. Sep 11 00:15:12.895500 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 11 00:15:12.895509 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 11 00:15:12.895517 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 11 00:15:12.895525 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 11 00:15:12.895533 kernel: ... version: 0 Sep 11 00:15:12.895543 kernel: ... bit width: 48 Sep 11 00:15:12.895551 kernel: ... generic registers: 6 Sep 11 00:15:12.895559 kernel: ... value mask: 0000ffffffffffff Sep 11 00:15:12.895567 kernel: ... max period: 00007fffffffffff Sep 11 00:15:12.895575 kernel: ... fixed-purpose events: 0 Sep 11 00:15:12.895583 kernel: ... event mask: 000000000000003f Sep 11 00:15:12.895590 kernel: signal: max sigframe size: 1776 Sep 11 00:15:12.895598 kernel: rcu: Hierarchical SRCU implementation. Sep 11 00:15:12.895606 kernel: rcu: Max phase no-delay instances is 400. Sep 11 00:15:12.895614 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 11 00:15:12.895625 kernel: smp: Bringing up secondary CPUs ... Sep 11 00:15:12.895633 kernel: smpboot: x86: Booting SMP configuration: Sep 11 00:15:12.895640 kernel: .... node #0, CPUs: #1 #2 #3 Sep 11 00:15:12.895648 kernel: smp: Brought up 1 node, 4 CPUs Sep 11 00:15:12.895656 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Sep 11 00:15:12.895665 kernel: Memory: 2428916K/2571752K available (14336K kernel code, 2429K rwdata, 9960K rodata, 54036K init, 2932K bss, 136904K reserved, 0K cma-reserved) Sep 11 00:15:12.895673 kernel: devtmpfs: initialized Sep 11 00:15:12.895681 kernel: x86/mm: Memory block size: 128MB Sep 11 00:15:12.895689 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 11 00:15:12.895710 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 11 00:15:12.895718 kernel: pinctrl core: initialized pinctrl subsystem Sep 11 00:15:12.895734 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 11 00:15:12.895744 kernel: audit: initializing netlink subsys (disabled) Sep 11 00:15:12.895752 kernel: audit: type=2000 audit(1757549710.203:1): state=initialized audit_enabled=0 res=1 Sep 11 00:15:12.895760 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 11 00:15:12.895768 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 11 00:15:12.895782 kernel: cpuidle: using governor menu Sep 11 00:15:12.895790 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 11 00:15:12.895801 kernel: dca service started, version 1.12.1 Sep 11 00:15:12.895810 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] Sep 11 00:15:12.895818 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 11 00:15:12.895826 kernel: PCI: Using configuration type 1 for base access Sep 11 00:15:12.895834 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 11 00:15:12.895842 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 11 00:15:12.895850 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 11 00:15:12.895858 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 11 00:15:12.895869 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 11 00:15:12.895877 kernel: ACPI: Added _OSI(Module Device) Sep 11 00:15:12.895885 kernel: ACPI: Added _OSI(Processor Device) Sep 11 00:15:12.895893 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 11 00:15:12.895901 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 11 00:15:12.895909 kernel: ACPI: Interpreter enabled Sep 11 00:15:12.895917 kernel: ACPI: PM: (supports S0 S3 S5) Sep 11 00:15:12.895925 kernel: ACPI: Using IOAPIC for interrupt routing Sep 11 00:15:12.895933 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 11 00:15:12.895941 kernel: PCI: Using E820 reservations for host bridge windows Sep 11 00:15:12.895952 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 11 00:15:12.895960 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 11 00:15:12.896230 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 11 00:15:12.896384 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 11 00:15:12.896553 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 11 00:15:12.896573 kernel: PCI host bridge to bus 0000:00 Sep 11 00:15:12.899534 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 11 00:15:12.899675 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 11 00:15:12.899789 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 11 00:15:12.899911 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 11 00:15:12.900025 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 11 00:15:12.900153 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Sep 11 00:15:12.900267 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 11 00:15:12.900457 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 11 00:15:12.900711 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 11 00:15:12.900859 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Sep 11 00:15:12.901023 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Sep 11 00:15:12.901162 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Sep 11 00:15:12.901290 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 11 00:15:12.901457 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 11 00:15:12.901631 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Sep 11 00:15:12.901771 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Sep 11 00:15:12.901897 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Sep 11 00:15:12.902056 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 11 00:15:12.902185 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Sep 11 00:15:12.902311 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Sep 11 00:15:12.902466 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Sep 11 00:15:12.902617 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 11 00:15:12.902846 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Sep 11 00:15:12.903221 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Sep 11 00:15:12.903436 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Sep 11 00:15:12.903604 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Sep 11 00:15:12.903948 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 11 00:15:12.904135 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 11 00:15:12.904283 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 11 00:15:12.904432 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Sep 11 00:15:12.904557 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Sep 11 00:15:12.904717 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 11 00:15:12.904848 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Sep 11 00:15:12.904860 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 11 00:15:12.904874 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 11 00:15:12.904882 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 11 00:15:12.904891 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 11 00:15:12.904899 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 11 00:15:12.904907 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 11 00:15:12.904916 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 11 00:15:12.904924 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 11 00:15:12.904933 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 11 00:15:12.904941 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 11 00:15:12.904952 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 11 00:15:12.904961 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 11 00:15:12.904969 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 11 00:15:12.904978 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 11 00:15:12.904989 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 11 00:15:12.904998 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 11 00:15:12.905006 kernel: iommu: Default domain type: Translated Sep 11 00:15:12.905014 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 11 00:15:12.905023 kernel: PCI: Using ACPI for IRQ routing Sep 11 00:15:12.905041 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 11 00:15:12.905051 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 11 00:15:12.905059 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Sep 11 00:15:12.905187 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 11 00:15:12.905315 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 11 00:15:12.905480 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 11 00:15:12.905493 kernel: vgaarb: loaded Sep 11 00:15:12.905501 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 11 00:15:12.905514 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 11 00:15:12.905523 kernel: clocksource: Switched to clocksource kvm-clock Sep 11 00:15:12.905531 kernel: VFS: Disk quotas dquot_6.6.0 Sep 11 00:15:12.905540 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 11 00:15:12.905548 kernel: pnp: PnP ACPI init Sep 11 00:15:12.905746 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 11 00:15:12.905760 kernel: pnp: PnP ACPI: found 6 devices Sep 11 00:15:12.905769 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 11 00:15:12.905782 kernel: NET: Registered PF_INET protocol family Sep 11 00:15:12.905790 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 11 00:15:12.905799 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 11 00:15:12.905816 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 11 00:15:12.905825 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 11 00:15:12.905839 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 11 00:15:12.905849 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 11 00:15:12.905857 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 11 00:15:12.905869 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 11 00:15:12.905886 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 11 00:15:12.905895 kernel: NET: Registered PF_XDP protocol family Sep 11 00:15:12.906023 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 11 00:15:12.906152 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 11 00:15:12.906270 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 11 00:15:12.906422 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 11 00:15:12.906539 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 11 00:15:12.906652 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Sep 11 00:15:12.906667 kernel: PCI: CLS 0 bytes, default 64 Sep 11 00:15:12.906676 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 11 00:15:12.906685 kernel: Initialise system trusted keyrings Sep 11 00:15:12.906694 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 11 00:15:12.906702 kernel: Key type asymmetric registered Sep 11 00:15:12.906711 kernel: Asymmetric key parser 'x509' registered Sep 11 00:15:12.906719 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 11 00:15:12.906728 kernel: io scheduler mq-deadline registered Sep 11 00:15:12.906736 kernel: io scheduler kyber registered Sep 11 00:15:12.906746 kernel: io scheduler bfq registered Sep 11 00:15:12.906755 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 11 00:15:12.906764 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 11 00:15:12.906773 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 11 00:15:12.906781 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 11 00:15:12.906790 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 11 00:15:12.906798 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 11 00:15:12.906807 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 11 00:15:12.906815 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 11 00:15:12.906823 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 11 00:15:12.906965 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 11 00:15:12.907094 kernel: rtc_cmos 00:04: registered as rtc0 Sep 11 00:15:12.907106 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 11 00:15:12.907237 kernel: rtc_cmos 00:04: setting system clock to 2025-09-11T00:15:12 UTC (1757549712) Sep 11 00:15:12.907398 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 11 00:15:12.907411 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 11 00:15:12.907420 kernel: NET: Registered PF_INET6 protocol family Sep 11 00:15:12.907442 kernel: Segment Routing with IPv6 Sep 11 00:15:12.907451 kernel: In-situ OAM (IOAM) with IPv6 Sep 11 00:15:12.907465 kernel: NET: Registered PF_PACKET protocol family Sep 11 00:15:12.907474 kernel: Key type dns_resolver registered Sep 11 00:15:12.907482 kernel: IPI shorthand broadcast: enabled Sep 11 00:15:12.907490 kernel: sched_clock: Marking stable (3361007473, 112219998)->(3494672921, -21445450) Sep 11 00:15:12.907499 kernel: registered taskstats version 1 Sep 11 00:15:12.907507 kernel: Loading compiled-in X.509 certificates Sep 11 00:15:12.907516 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: 941433bdd955e1c3aa4064827516bddd510466ee' Sep 11 00:15:12.907527 kernel: Demotion targets for Node 0: null Sep 11 00:15:12.907536 kernel: Key type .fscrypt registered Sep 11 00:15:12.907544 kernel: Key type fscrypt-provisioning registered Sep 11 00:15:12.907552 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 11 00:15:12.907561 kernel: ima: Allocated hash algorithm: sha1 Sep 11 00:15:12.907569 kernel: ima: No architecture policies found Sep 11 00:15:12.907577 kernel: clk: Disabling unused clocks Sep 11 00:15:12.907585 kernel: Warning: unable to open an initial console. Sep 11 00:15:12.907594 kernel: Freeing unused kernel image (initmem) memory: 54036K Sep 11 00:15:12.907604 kernel: Write protecting the kernel read-only data: 24576k Sep 11 00:15:12.907613 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 11 00:15:12.907621 kernel: Run /init as init process Sep 11 00:15:12.907629 kernel: with arguments: Sep 11 00:15:12.907638 kernel: /init Sep 11 00:15:12.907646 kernel: with environment: Sep 11 00:15:12.907654 kernel: HOME=/ Sep 11 00:15:12.907661 kernel: TERM=linux Sep 11 00:15:12.907670 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 11 00:15:12.907687 systemd[1]: Successfully made /usr/ read-only. Sep 11 00:15:12.907710 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 11 00:15:12.907722 systemd[1]: Detected virtualization kvm. Sep 11 00:15:12.907731 systemd[1]: Detected architecture x86-64. Sep 11 00:15:12.907740 systemd[1]: Running in initrd. Sep 11 00:15:12.907750 systemd[1]: No hostname configured, using default hostname. Sep 11 00:15:12.907760 systemd[1]: Hostname set to . Sep 11 00:15:12.907769 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:15:12.907777 systemd[1]: Queued start job for default target initrd.target. Sep 11 00:15:12.907787 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:15:12.907796 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:15:12.907805 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 11 00:15:12.907815 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:15:12.907826 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 11 00:15:12.907836 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 11 00:15:12.907846 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 11 00:15:12.907856 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 11 00:15:12.907867 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:15:12.907878 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:15:12.907890 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:15:12.907902 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:15:12.907911 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:15:12.907920 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:15:12.907931 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:15:12.907940 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:15:12.907949 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 11 00:15:12.907959 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 11 00:15:12.907968 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:15:12.907977 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:15:12.907988 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:15:12.907997 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:15:12.908005 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 11 00:15:12.908015 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:15:12.908026 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 11 00:15:12.908045 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 11 00:15:12.908055 systemd[1]: Starting systemd-fsck-usr.service... Sep 11 00:15:12.908064 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:15:12.908075 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:15:12.908084 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:15:12.908093 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 11 00:15:12.908105 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:15:12.908114 systemd[1]: Finished systemd-fsck-usr.service. Sep 11 00:15:12.908123 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:15:12.908173 systemd-journald[219]: Collecting audit messages is disabled. Sep 11 00:15:12.908199 systemd-journald[219]: Journal started Sep 11 00:15:12.908221 systemd-journald[219]: Runtime Journal (/run/log/journal/9a896827bfea4ec5bf1f12510f20db3f) is 6M, max 48.6M, 42.5M free. Sep 11 00:15:12.895831 systemd-modules-load[221]: Inserted module 'overlay' Sep 11 00:15:12.943682 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:15:12.943741 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 11 00:15:12.943765 kernel: Bridge firewalling registered Sep 11 00:15:12.935699 systemd-modules-load[221]: Inserted module 'br_netfilter' Sep 11 00:15:12.943409 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:15:12.952260 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:15:12.955231 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:15:12.971265 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 11 00:15:12.975063 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:15:12.977495 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:15:13.144437 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:15:13.157592 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 11 00:15:13.158764 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:15:13.161195 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:15:13.163704 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:15:13.166684 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:15:13.173160 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:15:13.181812 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 11 00:15:13.222724 systemd-resolved[259]: Positive Trust Anchors: Sep 11 00:15:13.222758 systemd-resolved[259]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:15:13.222788 systemd-resolved[259]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 11 00:15:13.226607 systemd-resolved[259]: Defaulting to hostname 'linux'. Sep 11 00:15:13.228552 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:15:13.233248 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:15:13.239973 dracut-cmdline[263]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=20820f07706ad5590d38fe5324b9055d59a89dc1109fdc449cad1a53209b9dbd Sep 11 00:15:13.356396 kernel: SCSI subsystem initialized Sep 11 00:15:13.367362 kernel: Loading iSCSI transport class v2.0-870. Sep 11 00:15:13.378375 kernel: iscsi: registered transport (tcp) Sep 11 00:15:13.400684 kernel: iscsi: registered transport (qla4xxx) Sep 11 00:15:13.400747 kernel: QLogic iSCSI HBA Driver Sep 11 00:15:13.424047 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:15:13.459609 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:15:13.463717 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:15:13.524132 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 11 00:15:13.527075 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 11 00:15:13.591381 kernel: raid6: avx2x4 gen() 22231 MB/s Sep 11 00:15:13.608394 kernel: raid6: avx2x2 gen() 27309 MB/s Sep 11 00:15:13.625452 kernel: raid6: avx2x1 gen() 24815 MB/s Sep 11 00:15:13.625530 kernel: raid6: using algorithm avx2x2 gen() 27309 MB/s Sep 11 00:15:13.643586 kernel: raid6: .... xor() 19152 MB/s, rmw enabled Sep 11 00:15:13.643687 kernel: raid6: using avx2x2 recovery algorithm Sep 11 00:15:13.665401 kernel: xor: automatically using best checksumming function avx Sep 11 00:15:13.880405 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 11 00:15:13.891986 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:15:13.894502 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:15:13.939170 systemd-udevd[471]: Using default interface naming scheme 'v255'. Sep 11 00:15:13.946496 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:15:13.950506 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 11 00:15:13.981238 dracut-pre-trigger[481]: rd.md=0: removing MD RAID activation Sep 11 00:15:14.017034 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:15:14.020732 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:15:14.101897 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:15:14.137849 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 11 00:15:14.180389 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 11 00:15:14.183905 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 11 00:15:14.193472 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:15:14.193498 kernel: cryptd: max_cpu_qlen set to 1000 Sep 11 00:15:14.197360 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 11 00:15:14.233824 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:15:14.233978 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:15:14.242635 kernel: AES CTR mode by8 optimization enabled Sep 11 00:15:14.242669 kernel: libata version 3.00 loaded. Sep 11 00:15:14.246716 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:15:14.252426 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:15:14.255315 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:15:14.296305 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 11 00:15:14.317727 kernel: ahci 0000:00:1f.2: version 3.0 Sep 11 00:15:14.317947 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 11 00:15:14.317986 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 11 00:15:14.318164 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 11 00:15:14.318332 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 11 00:15:14.322367 kernel: scsi host0: ahci Sep 11 00:15:14.335218 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 11 00:15:14.352325 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 11 00:15:14.352937 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 11 00:15:14.363884 kernel: scsi host1: ahci Sep 11 00:15:14.364642 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:15:14.367129 kernel: scsi host2: ahci Sep 11 00:15:14.367534 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 11 00:15:14.371503 kernel: scsi host3: ahci Sep 11 00:15:14.373384 kernel: scsi host4: ahci Sep 11 00:15:14.373671 kernel: scsi host5: ahci Sep 11 00:15:14.377386 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 lpm-pol 1 Sep 11 00:15:14.377459 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 lpm-pol 1 Sep 11 00:15:14.377476 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 lpm-pol 1 Sep 11 00:15:14.377489 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 lpm-pol 1 Sep 11 00:15:14.378763 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 lpm-pol 1 Sep 11 00:15:14.378789 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 lpm-pol 1 Sep 11 00:15:14.551655 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:15:14.577379 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:15:14.587370 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:15:14.686397 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 11 00:15:14.689375 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 11 00:15:14.702388 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 11 00:15:14.702481 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 11 00:15:14.703369 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 11 00:15:14.704378 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 11 00:15:14.705382 kernel: ata3.00: LPM support broken, forcing max_power Sep 11 00:15:14.705410 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 11 00:15:14.705787 kernel: ata3.00: applying bridge limits Sep 11 00:15:14.706361 kernel: ata3.00: LPM support broken, forcing max_power Sep 11 00:15:14.707460 kernel: ata3.00: configured for UDMA/100 Sep 11 00:15:14.708355 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 11 00:15:14.767396 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 11 00:15:14.767844 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 11 00:15:14.793388 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 11 00:15:15.178478 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 11 00:15:15.180405 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:15:15.182490 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:15:15.182742 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:15:15.184397 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 11 00:15:15.212518 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:15:15.593380 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:15:15.594374 disk-uuid[639]: The operation has completed successfully. Sep 11 00:15:15.629267 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 11 00:15:15.629417 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 11 00:15:15.665434 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 11 00:15:15.694669 sh[668]: Success Sep 11 00:15:15.716130 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 11 00:15:15.716203 kernel: device-mapper: uevent: version 1.0.3 Sep 11 00:15:15.716217 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 11 00:15:15.726368 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 11 00:15:15.760473 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 11 00:15:15.765280 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 11 00:15:15.780840 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 11 00:15:15.787397 kernel: BTRFS: device fsid 1d23f222-37c7-4ff5-813e-235ce83bed46 devid 1 transid 40 /dev/mapper/usr (253:0) scanned by mount (680) Sep 11 00:15:15.789437 kernel: BTRFS info (device dm-0): first mount of filesystem 1d23f222-37c7-4ff5-813e-235ce83bed46 Sep 11 00:15:15.789467 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:15:15.794916 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 11 00:15:15.794974 kernel: BTRFS info (device dm-0): enabling free space tree Sep 11 00:15:15.796630 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 11 00:15:15.798169 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:15:15.799736 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 11 00:15:15.800647 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 11 00:15:15.802421 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 11 00:15:15.834394 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (711) Sep 11 00:15:15.836714 kernel: BTRFS info (device vda6): first mount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:15:15.836752 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:15:15.840385 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:15:15.840435 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:15:15.846423 kernel: BTRFS info (device vda6): last unmount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:15:15.847938 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 11 00:15:15.850270 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 11 00:15:16.055191 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:15:16.057130 ignition[756]: Ignition 2.21.0 Sep 11 00:15:16.057139 ignition[756]: Stage: fetch-offline Sep 11 00:15:16.057195 ignition[756]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:15:16.058869 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:15:16.057207 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:15:16.057356 ignition[756]: parsed url from cmdline: "" Sep 11 00:15:16.057362 ignition[756]: no config URL provided Sep 11 00:15:16.057369 ignition[756]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:15:16.057381 ignition[756]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:15:16.057412 ignition[756]: op(1): [started] loading QEMU firmware config module Sep 11 00:15:16.057419 ignition[756]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 11 00:15:16.067116 ignition[756]: op(1): [finished] loading QEMU firmware config module Sep 11 00:15:16.071525 ignition[756]: parsing config with SHA512: a0b888cd7954e240383f78b6bf910dba7ceb69f5a9d8f06750b390c8a7cb00dbe0b0404c071cfa181f0850b1e89d8a3298ee3118e6d5f3695d464b312adef928 Sep 11 00:15:16.078077 unknown[756]: fetched base config from "system" Sep 11 00:15:16.078095 unknown[756]: fetched user config from "qemu" Sep 11 00:15:16.078335 ignition[756]: fetch-offline: fetch-offline passed Sep 11 00:15:16.078471 ignition[756]: Ignition finished successfully Sep 11 00:15:16.081661 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:15:16.135276 systemd-networkd[861]: lo: Link UP Sep 11 00:15:16.135288 systemd-networkd[861]: lo: Gained carrier Sep 11 00:15:16.137008 systemd-networkd[861]: Enumeration completed Sep 11 00:15:16.137363 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:15:16.137523 systemd-networkd[861]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:15:16.137528 systemd-networkd[861]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:15:16.138670 systemd-networkd[861]: eth0: Link UP Sep 11 00:15:16.138822 systemd-networkd[861]: eth0: Gained carrier Sep 11 00:15:16.138833 systemd-networkd[861]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:15:16.140073 systemd[1]: Reached target network.target - Network. Sep 11 00:15:16.142086 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 11 00:15:16.143224 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 11 00:15:16.151430 systemd-networkd[861]: eth0: DHCPv4 address 10.0.0.54/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 11 00:15:16.184927 ignition[866]: Ignition 2.21.0 Sep 11 00:15:16.184968 ignition[866]: Stage: kargs Sep 11 00:15:16.185210 ignition[866]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:15:16.185228 ignition[866]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:15:16.188850 ignition[866]: kargs: kargs passed Sep 11 00:15:16.188964 ignition[866]: Ignition finished successfully Sep 11 00:15:16.196823 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 11 00:15:16.200108 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 11 00:15:16.231593 ignition[875]: Ignition 2.21.0 Sep 11 00:15:16.231607 ignition[875]: Stage: disks Sep 11 00:15:16.231793 ignition[875]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:15:16.231804 ignition[875]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:15:16.234435 ignition[875]: disks: disks passed Sep 11 00:15:16.234517 ignition[875]: Ignition finished successfully Sep 11 00:15:16.237684 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 11 00:15:16.240865 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 11 00:15:16.240998 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 11 00:15:16.243644 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:15:16.246275 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:15:16.246800 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:15:16.252115 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 11 00:15:16.291800 systemd-resolved[259]: Detected conflict on linux IN A 10.0.0.54 Sep 11 00:15:16.291821 systemd-resolved[259]: Hostname conflict, changing published hostname from 'linux' to 'linux6'. Sep 11 00:15:16.295054 systemd-fsck[885]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 11 00:15:16.307370 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 11 00:15:16.310993 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 11 00:15:16.444400 kernel: EXT4-fs (vda9): mounted filesystem 8ebc908f-0860-41e2-beed-287b778bd592 r/w with ordered data mode. Quota mode: none. Sep 11 00:15:16.445327 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 11 00:15:16.446185 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 11 00:15:16.451241 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:15:16.453083 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 11 00:15:16.456217 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 11 00:15:16.456300 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 11 00:15:16.458091 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:15:16.469779 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 11 00:15:16.473676 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 11 00:15:16.478712 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (893) Sep 11 00:15:16.478737 kernel: BTRFS info (device vda6): first mount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:15:16.478749 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:15:16.480372 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:15:16.480401 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:15:16.483672 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:15:16.539214 initrd-setup-root[917]: cut: /sysroot/etc/passwd: No such file or directory Sep 11 00:15:16.545036 initrd-setup-root[924]: cut: /sysroot/etc/group: No such file or directory Sep 11 00:15:16.552007 initrd-setup-root[931]: cut: /sysroot/etc/shadow: No such file or directory Sep 11 00:15:16.556713 initrd-setup-root[938]: cut: /sysroot/etc/gshadow: No such file or directory Sep 11 00:15:16.694749 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 11 00:15:16.696227 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 11 00:15:16.698432 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 11 00:15:16.733380 kernel: BTRFS info (device vda6): last unmount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:15:16.747519 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 11 00:15:16.766807 ignition[1007]: INFO : Ignition 2.21.0 Sep 11 00:15:16.768134 ignition[1007]: INFO : Stage: mount Sep 11 00:15:16.768134 ignition[1007]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:15:16.768134 ignition[1007]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:15:16.771233 ignition[1007]: INFO : mount: mount passed Sep 11 00:15:16.771233 ignition[1007]: INFO : Ignition finished successfully Sep 11 00:15:16.773616 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 11 00:15:16.777657 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 11 00:15:16.788539 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 11 00:15:16.815001 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:15:16.861395 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1019) Sep 11 00:15:16.861520 kernel: BTRFS info (device vda6): first mount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:15:16.863581 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:15:16.867932 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:15:16.867969 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:15:16.870174 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:15:16.937742 ignition[1036]: INFO : Ignition 2.21.0 Sep 11 00:15:16.939008 ignition[1036]: INFO : Stage: files Sep 11 00:15:16.939008 ignition[1036]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:15:16.939008 ignition[1036]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:15:16.942251 ignition[1036]: DEBUG : files: compiled without relabeling support, skipping Sep 11 00:15:16.942251 ignition[1036]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 11 00:15:16.942251 ignition[1036]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 11 00:15:16.946491 ignition[1036]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 11 00:15:16.946491 ignition[1036]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 11 00:15:16.946491 ignition[1036]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 11 00:15:16.945247 unknown[1036]: wrote ssh authorized keys file for user: core Sep 11 00:15:16.952459 ignition[1036]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:15:16.954415 ignition[1036]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:15:16.954415 ignition[1036]: INFO : files: op(4): [started] processing unit "etcd-member.service" Sep 11 00:15:16.954415 ignition[1036]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 11 00:15:16.960915 ignition[1036]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Sep 11 00:15:16.960915 ignition[1036]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Sep 11 00:15:16.960915 ignition[1036]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Sep 11 00:15:16.966886 ignition[1036]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 11 00:15:16.966886 ignition[1036]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 11 00:15:16.966886 ignition[1036]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Sep 11 00:15:16.966886 ignition[1036]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Sep 11 00:15:16.985197 ignition[1036]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 11 00:15:16.992212 ignition[1036]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 11 00:15:16.994015 ignition[1036]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Sep 11 00:15:16.994015 ignition[1036]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Sep 11 00:15:16.994015 ignition[1036]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Sep 11 00:15:16.994015 ignition[1036]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:15:16.994015 ignition[1036]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:15:16.994015 ignition[1036]: INFO : files: files passed Sep 11 00:15:16.994015 ignition[1036]: INFO : Ignition finished successfully Sep 11 00:15:16.998965 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 11 00:15:17.006519 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 11 00:15:17.009198 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 11 00:15:17.026922 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 11 00:15:17.027075 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 11 00:15:17.030653 initrd-setup-root-after-ignition[1065]: grep: /sysroot/oem/oem-release: No such file or directory Sep 11 00:15:17.034998 initrd-setup-root-after-ignition[1067]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:15:17.036773 initrd-setup-root-after-ignition[1067]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:15:17.038559 initrd-setup-root-after-ignition[1071]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:15:17.039518 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:15:17.040578 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 11 00:15:17.044857 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 11 00:15:17.127508 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 11 00:15:17.127687 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 11 00:15:17.130617 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 11 00:15:17.131260 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 11 00:15:17.133514 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 11 00:15:17.135570 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 11 00:15:17.172955 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:15:17.175287 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 11 00:15:17.199542 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:15:17.201212 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:15:17.201423 systemd[1]: Stopped target timers.target - Timer Units. Sep 11 00:15:17.205405 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 11 00:15:17.205595 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:15:17.209612 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 11 00:15:17.212114 systemd[1]: Stopped target basic.target - Basic System. Sep 11 00:15:17.214288 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 11 00:15:17.216623 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:15:17.218990 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 11 00:15:17.222399 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:15:17.223001 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 11 00:15:17.223445 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:15:17.224012 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 11 00:15:17.230630 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 11 00:15:17.233522 systemd[1]: Stopped target swap.target - Swaps. Sep 11 00:15:17.235595 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 11 00:15:17.235789 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:15:17.240784 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:15:17.241019 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:15:17.245857 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 11 00:15:17.247009 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:15:17.249569 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 11 00:15:17.249718 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 11 00:15:17.252729 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 11 00:15:17.252893 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:15:17.254106 systemd[1]: Stopped target paths.target - Path Units. Sep 11 00:15:17.256131 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 11 00:15:17.261461 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:15:17.261664 systemd[1]: Stopped target slices.target - Slice Units. Sep 11 00:15:17.264201 systemd[1]: Stopped target sockets.target - Socket Units. Sep 11 00:15:17.264678 systemd[1]: iscsid.socket: Deactivated successfully. Sep 11 00:15:17.264787 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:15:17.267530 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 11 00:15:17.267624 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:15:17.269427 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 11 00:15:17.269562 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:15:17.269873 systemd[1]: ignition-files.service: Deactivated successfully. Sep 11 00:15:17.270001 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 11 00:15:17.277242 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 11 00:15:17.278808 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 11 00:15:17.278968 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:15:17.282026 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 11 00:15:17.283668 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 11 00:15:17.283794 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:15:17.286239 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 11 00:15:17.286371 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:15:17.295546 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 11 00:15:17.295729 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 11 00:15:17.312956 ignition[1091]: INFO : Ignition 2.21.0 Sep 11 00:15:17.312956 ignition[1091]: INFO : Stage: umount Sep 11 00:15:17.315537 ignition[1091]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:15:17.315537 ignition[1091]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:15:17.315537 ignition[1091]: INFO : umount: umount passed Sep 11 00:15:17.315537 ignition[1091]: INFO : Ignition finished successfully Sep 11 00:15:17.316624 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 11 00:15:17.316774 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 11 00:15:17.318173 systemd[1]: Stopped target network.target - Network. Sep 11 00:15:17.319943 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 11 00:15:17.320042 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 11 00:15:17.320392 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 11 00:15:17.320488 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 11 00:15:17.320919 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 11 00:15:17.321032 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 11 00:15:17.325771 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 11 00:15:17.325831 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 11 00:15:17.326439 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 11 00:15:17.329808 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 11 00:15:17.334166 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 11 00:15:17.339850 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 11 00:15:17.340016 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 11 00:15:17.342443 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 11 00:15:17.342517 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 11 00:15:17.345719 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 11 00:15:17.345908 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 11 00:15:17.351753 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 11 00:15:17.352115 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 11 00:15:17.352295 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 11 00:15:17.357123 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 11 00:15:17.358464 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 11 00:15:17.359187 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 11 00:15:17.359262 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:15:17.364177 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 11 00:15:17.367137 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 11 00:15:17.367208 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:15:17.370951 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 11 00:15:17.371075 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:15:17.374195 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 11 00:15:17.374282 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 11 00:15:17.377647 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 11 00:15:17.377742 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:15:17.381714 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:15:17.407752 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 11 00:15:17.407910 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 11 00:15:17.410603 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 11 00:15:17.410930 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:15:17.413445 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 11 00:15:17.413528 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 11 00:15:17.415083 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 11 00:15:17.415134 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:15:17.416444 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 11 00:15:17.416505 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:15:17.420176 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 11 00:15:17.420239 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 11 00:15:17.421112 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 11 00:15:17.421177 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:15:17.429212 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 11 00:15:17.430203 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 11 00:15:17.430292 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:15:17.435090 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 11 00:15:17.435166 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:15:17.440461 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:15:17.440549 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:15:17.459116 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 11 00:15:17.459289 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 11 00:15:17.462273 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 11 00:15:17.465665 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 11 00:15:17.495215 systemd[1]: Switching root. Sep 11 00:15:17.535681 systemd-journald[219]: Journal stopped Sep 11 00:15:18.798736 systemd-journald[219]: Received SIGTERM from PID 1 (systemd). Sep 11 00:15:18.798834 kernel: SELinux: policy capability network_peer_controls=1 Sep 11 00:15:18.798854 kernel: SELinux: policy capability open_perms=1 Sep 11 00:15:18.798869 kernel: SELinux: policy capability extended_socket_class=1 Sep 11 00:15:18.798902 kernel: SELinux: policy capability always_check_network=0 Sep 11 00:15:18.798919 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 11 00:15:18.798935 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 11 00:15:18.798950 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 11 00:15:18.798973 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 11 00:15:18.798989 kernel: SELinux: policy capability userspace_initial_context=0 Sep 11 00:15:18.799004 kernel: audit: type=1403 audit(1757549717.664:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 11 00:15:18.799034 systemd[1]: Successfully loaded SELinux policy in 66.923ms. Sep 11 00:15:18.799062 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.241ms. Sep 11 00:15:18.799081 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 11 00:15:18.799099 systemd[1]: Detected virtualization kvm. Sep 11 00:15:18.799116 systemd[1]: Detected architecture x86-64. Sep 11 00:15:18.799132 systemd[1]: Detected first boot. Sep 11 00:15:18.799148 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:15:18.799169 zram_generator::config[1137]: No configuration found. Sep 11 00:15:18.799187 kernel: Guest personality initialized and is inactive Sep 11 00:15:18.799210 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 11 00:15:18.799226 kernel: Initialized host personality Sep 11 00:15:18.799241 kernel: NET: Registered PF_VSOCK protocol family Sep 11 00:15:18.799256 systemd[1]: Populated /etc with preset unit settings. Sep 11 00:15:18.799274 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 11 00:15:18.799290 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 11 00:15:18.799310 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 11 00:15:18.799326 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 11 00:15:18.799362 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 11 00:15:18.799380 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 11 00:15:18.799396 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 11 00:15:18.799413 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 11 00:15:18.799430 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 11 00:15:18.799446 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 11 00:15:18.799463 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 11 00:15:18.799485 systemd[1]: Created slice user.slice - User and Session Slice. Sep 11 00:15:18.799502 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:15:18.799518 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:15:18.799535 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 11 00:15:18.799551 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 11 00:15:18.799569 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 11 00:15:18.799586 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:15:18.799607 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 11 00:15:18.799624 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:15:18.799641 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:15:18.799657 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 11 00:15:18.799673 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 11 00:15:18.799690 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 11 00:15:18.799708 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 11 00:15:18.799725 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:15:18.799742 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:15:18.799758 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:15:18.799778 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:15:18.799795 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 11 00:15:18.799814 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 11 00:15:18.799832 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 11 00:15:18.799852 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:15:18.799871 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:15:18.799899 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:15:18.799916 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 11 00:15:18.799934 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 11 00:15:18.799957 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 11 00:15:18.799974 systemd[1]: Mounting media.mount - External Media Directory... Sep 11 00:15:18.799991 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:15:18.800009 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 11 00:15:18.800026 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 11 00:15:18.800044 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 11 00:15:18.800062 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 11 00:15:18.800079 systemd[1]: Reached target machines.target - Containers. Sep 11 00:15:18.800101 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 11 00:15:18.800118 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:15:18.800135 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:15:18.800151 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 11 00:15:18.800168 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:15:18.800185 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:15:18.800202 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:15:18.800220 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 11 00:15:18.800237 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:15:18.800266 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 11 00:15:18.800284 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 11 00:15:18.800302 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 11 00:15:18.800320 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 11 00:15:18.800336 systemd[1]: Stopped systemd-fsck-usr.service. Sep 11 00:15:18.800378 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:15:18.800395 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:15:18.800412 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:15:18.800437 kernel: fuse: init (API version 7.41) Sep 11 00:15:18.800454 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:15:18.800471 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 11 00:15:18.800487 kernel: loop: module loaded Sep 11 00:15:18.800503 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 11 00:15:18.800520 kernel: ACPI: bus type drm_connector registered Sep 11 00:15:18.800536 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:15:18.800556 systemd[1]: verity-setup.service: Deactivated successfully. Sep 11 00:15:18.800573 systemd[1]: Stopped verity-setup.service. Sep 11 00:15:18.800591 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:15:18.800608 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 11 00:15:18.800625 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 11 00:15:18.800642 systemd[1]: Mounted media.mount - External Media Directory. Sep 11 00:15:18.800660 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 11 00:15:18.800681 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 11 00:15:18.800697 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 11 00:15:18.800714 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:15:18.800735 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 11 00:15:18.800752 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 11 00:15:18.800772 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:15:18.800790 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:15:18.800806 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:15:18.800823 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:15:18.800848 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:15:18.800866 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:15:18.800882 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 11 00:15:18.800910 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 11 00:15:18.800927 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:15:18.800948 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:15:18.801002 systemd-journald[1205]: Collecting audit messages is disabled. Sep 11 00:15:18.801034 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:15:18.801052 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:15:18.801070 systemd-journald[1205]: Journal started Sep 11 00:15:18.801103 systemd-journald[1205]: Runtime Journal (/run/log/journal/9a896827bfea4ec5bf1f12510f20db3f) is 6M, max 48.6M, 42.5M free. Sep 11 00:15:18.287269 systemd[1]: Queued start job for default target multi-user.target. Sep 11 00:15:18.316017 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 11 00:15:18.316894 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 11 00:15:18.805371 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:15:18.807612 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 11 00:15:18.809670 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 11 00:15:18.842124 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:15:18.847076 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 11 00:15:18.852168 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 11 00:15:18.853989 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 11 00:15:18.854038 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:15:18.856529 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 11 00:15:18.867459 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 11 00:15:18.868866 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:15:18.871989 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 11 00:15:18.881060 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 11 00:15:18.884486 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:15:18.887519 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 11 00:15:18.889067 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:15:18.893572 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:15:18.898520 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 11 00:15:18.903290 systemd-journald[1205]: Time spent on flushing to /var/log/journal/9a896827bfea4ec5bf1f12510f20db3f is 49.802ms for 951 entries. Sep 11 00:15:18.903290 systemd-journald[1205]: System Journal (/var/log/journal/9a896827bfea4ec5bf1f12510f20db3f) is 8M, max 195.6M, 187.6M free. Sep 11 00:15:18.997954 systemd-journald[1205]: Received client request to flush runtime journal. Sep 11 00:15:18.998014 kernel: loop0: detected capacity change from 0 to 111000 Sep 11 00:15:18.998035 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 11 00:15:18.904878 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 11 00:15:18.907194 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 11 00:15:18.915388 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 11 00:15:18.918853 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 11 00:15:18.920660 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:15:18.954761 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 11 00:15:18.957005 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:15:18.958985 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 11 00:15:18.964235 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 11 00:15:19.008160 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 11 00:15:19.026999 kernel: loop1: detected capacity change from 0 to 128016 Sep 11 00:15:19.031490 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 11 00:15:19.044369 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 11 00:15:19.049386 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:15:19.064382 kernel: loop2: detected capacity change from 0 to 111000 Sep 11 00:15:19.078375 kernel: loop3: detected capacity change from 0 to 128016 Sep 11 00:15:19.086358 systemd-tmpfiles[1274]: ACLs are not supported, ignoring. Sep 11 00:15:19.086452 systemd-tmpfiles[1274]: ACLs are not supported, ignoring. Sep 11 00:15:19.094553 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:15:19.096913 (sd-merge)[1275]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 11 00:15:19.097643 (sd-merge)[1275]: Merged extensions into '/usr'. Sep 11 00:15:19.103037 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 11 00:15:19.108436 systemd[1]: Starting ensure-sysext.service... Sep 11 00:15:19.119265 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:15:19.134311 systemd[1]: Reload requested from client PID 1279 ('systemctl') (unit ensure-sysext.service)... Sep 11 00:15:19.134474 systemd[1]: Reloading... Sep 11 00:15:19.153650 systemd-tmpfiles[1280]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 11 00:15:19.153709 systemd-tmpfiles[1280]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 11 00:15:19.154147 systemd-tmpfiles[1280]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 11 00:15:19.154560 systemd-tmpfiles[1280]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 11 00:15:19.155696 systemd-tmpfiles[1280]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 11 00:15:19.156032 systemd-tmpfiles[1280]: ACLs are not supported, ignoring. Sep 11 00:15:19.156109 systemd-tmpfiles[1280]: ACLs are not supported, ignoring. Sep 11 00:15:19.162053 systemd-tmpfiles[1280]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:15:19.162275 systemd-tmpfiles[1280]: Skipping /boot Sep 11 00:15:19.175354 systemd-tmpfiles[1280]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:15:19.176035 systemd-tmpfiles[1280]: Skipping /boot Sep 11 00:15:19.227480 zram_generator::config[1307]: No configuration found. Sep 11 00:15:19.500739 ldconfig[1250]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:15:19.531589 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 11 00:15:19.532212 systemd[1]: Reloading finished in 397 ms. Sep 11 00:15:19.586510 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 11 00:15:19.611695 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:15:19.623595 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:15:19.627076 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 11 00:15:19.631850 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 11 00:15:19.657755 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:15:19.664167 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 11 00:15:19.670399 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:15:19.670769 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:15:19.673681 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:15:19.677738 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:15:19.690878 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:15:19.692590 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:15:19.692934 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:15:19.693224 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:15:19.695282 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:15:19.700804 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:15:19.708954 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:15:19.709333 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:15:19.712863 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:15:19.714670 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:15:19.717230 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 11 00:15:19.730669 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 11 00:15:19.741819 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:15:19.742189 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:15:19.744642 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:15:19.745542 augenrules[1379]: No rules Sep 11 00:15:19.750102 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:15:19.762670 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:15:19.766180 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:15:19.768613 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:15:19.768766 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 11 00:15:19.771765 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 11 00:15:19.773094 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:15:19.776068 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:15:19.776705 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:15:19.779087 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 11 00:15:19.781377 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:15:19.794751 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:15:19.797243 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:15:19.797625 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:15:19.801437 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:15:19.801924 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:15:19.804452 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:15:19.805103 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:15:19.815398 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:15:19.815773 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:15:19.815826 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 11 00:15:19.818132 systemd[1]: Finished ensure-sysext.service. Sep 11 00:15:19.823639 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 11 00:15:19.862556 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 11 00:15:19.949823 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 11 00:15:19.951440 systemd[1]: Reached target time-set.target - System Time Set. Sep 11 00:15:19.955999 systemd-resolved[1350]: Positive Trust Anchors: Sep 11 00:15:19.956019 systemd-resolved[1350]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:15:19.956051 systemd-resolved[1350]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 11 00:15:19.961652 systemd-resolved[1350]: Defaulting to hostname 'linux'. Sep 11 00:15:19.963281 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:15:19.964732 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:15:20.020503 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 11 00:15:20.024408 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:15:20.027605 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 11 00:15:20.064756 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 11 00:15:20.080349 systemd-udevd[1405]: Using default interface naming scheme 'v255'. Sep 11 00:15:20.102921 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:15:20.104903 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:15:20.106565 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 11 00:15:20.108106 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 11 00:15:20.110243 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 11 00:15:20.112403 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 11 00:15:20.113737 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 11 00:15:20.115321 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 11 00:15:20.116832 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 11 00:15:20.116870 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:15:20.117994 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:15:20.123959 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 11 00:15:20.129577 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 11 00:15:20.150535 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 11 00:15:20.152226 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 11 00:15:20.153657 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 11 00:15:20.166291 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 11 00:15:20.168662 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 11 00:15:20.176733 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:15:20.179143 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 11 00:15:20.190332 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:15:20.192207 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:15:20.193324 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:15:20.193383 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:15:20.201685 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 11 00:15:20.208887 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 11 00:15:20.212746 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 11 00:15:20.216901 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 11 00:15:20.218124 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 11 00:15:20.220064 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 11 00:15:20.223764 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 11 00:15:20.228081 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 11 00:15:20.235133 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 11 00:15:20.240885 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 11 00:15:20.243555 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 11 00:15:20.244769 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 11 00:15:20.250630 jq[1441]: false Sep 11 00:15:20.251480 systemd[1]: Starting update-engine.service - Update Engine... Sep 11 00:15:20.255474 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 11 00:15:20.259664 google_oslogin_nss_cache[1443]: oslogin_cache_refresh[1443]: Refreshing passwd entry cache Sep 11 00:15:20.259695 oslogin_cache_refresh[1443]: Refreshing passwd entry cache Sep 11 00:15:20.263915 google_oslogin_nss_cache[1443]: oslogin_cache_refresh[1443]: Failure getting users, quitting Sep 11 00:15:20.263915 google_oslogin_nss_cache[1443]: oslogin_cache_refresh[1443]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:15:20.263915 google_oslogin_nss_cache[1443]: oslogin_cache_refresh[1443]: Refreshing group entry cache Sep 11 00:15:20.263618 oslogin_cache_refresh[1443]: Failure getting users, quitting Sep 11 00:15:20.263639 oslogin_cache_refresh[1443]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:15:20.263693 oslogin_cache_refresh[1443]: Refreshing group entry cache Sep 11 00:15:20.264383 google_oslogin_nss_cache[1443]: oslogin_cache_refresh[1443]: Failure getting groups, quitting Sep 11 00:15:20.264383 google_oslogin_nss_cache[1443]: oslogin_cache_refresh[1443]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:15:20.264317 oslogin_cache_refresh[1443]: Failure getting groups, quitting Sep 11 00:15:20.264328 oslogin_cache_refresh[1443]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:15:20.265648 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 11 00:15:20.268010 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 11 00:15:20.268390 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 11 00:15:20.268874 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 11 00:15:20.269616 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 11 00:15:20.272018 systemd[1]: motdgen.service: Deactivated successfully. Sep 11 00:15:20.272281 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 11 00:15:20.274881 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 11 00:15:20.275191 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 11 00:15:20.293535 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 11 00:15:20.295092 update_engine[1448]: I20250911 00:15:20.295006 1448 main.cc:92] Flatcar Update Engine starting Sep 11 00:15:20.301892 extend-filesystems[1442]: Found /dev/vda6 Sep 11 00:15:20.306721 extend-filesystems[1442]: Found /dev/vda9 Sep 11 00:15:20.319517 jq[1453]: true Sep 11 00:15:20.329118 extend-filesystems[1442]: Checking size of /dev/vda9 Sep 11 00:15:20.351198 extend-filesystems[1442]: Old size kept for /dev/vda9 Sep 11 00:15:20.356919 jq[1472]: true Sep 11 00:15:20.352715 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 11 00:15:20.353061 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 11 00:15:20.360748 dbus-daemon[1437]: [system] SELinux support is enabled Sep 11 00:15:20.360974 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 11 00:15:20.364726 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 11 00:15:20.364755 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 11 00:15:20.366483 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 11 00:15:20.366501 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 11 00:15:20.370670 update_engine[1448]: I20250911 00:15:20.370599 1448 update_check_scheduler.cc:74] Next update check in 3m21s Sep 11 00:15:20.373110 systemd[1]: Started update-engine.service - Update Engine. Sep 11 00:15:20.386665 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 11 00:15:20.402978 systemd-logind[1447]: New seat seat0. Sep 11 00:15:20.403369 systemd-networkd[1435]: lo: Link UP Sep 11 00:15:20.403379 systemd-networkd[1435]: lo: Gained carrier Sep 11 00:15:20.409457 systemd-networkd[1435]: Enumeration completed Sep 11 00:15:20.409608 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:15:20.447827 bash[1499]: Updated "/home/core/.ssh/authorized_keys" Sep 11 00:15:20.455661 locksmithd[1485]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 11 00:15:20.455778 systemd-networkd[1435]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:15:20.455784 systemd-networkd[1435]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:15:20.456679 systemd-networkd[1435]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:15:20.456742 systemd-networkd[1435]: eth0: Link UP Sep 11 00:15:20.457026 systemd-networkd[1435]: eth0: Gained carrier Sep 11 00:15:20.457055 systemd-networkd[1435]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:15:20.471390 kernel: mousedev: PS/2 mouse device common for all mice Sep 11 00:15:20.474935 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 11 00:15:20.475457 systemd-networkd[1435]: eth0: DHCPv4 address 10.0.0.54/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 11 00:15:20.478544 systemd-timesyncd[1396]: Network configuration changed, trying to establish connection. Sep 11 00:15:21.545777 systemd-timesyncd[1396]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 11 00:15:21.545918 systemd-timesyncd[1396]: Initial clock synchronization to Thu 2025-09-11 00:15:21.545445 UTC. Sep 11 00:15:21.550079 systemd-resolved[1350]: Clock change detected. Flushing caches. Sep 11 00:15:21.568884 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 Sep 11 00:15:21.564953 systemd[1]: Started systemd-logind.service - User Login Management. Sep 11 00:15:21.636074 kernel: ACPI: button: Power Button [PWRF] Sep 11 00:15:21.662220 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:15:21.664824 systemd[1]: Reached target network.target - Network. Sep 11 00:15:21.669404 systemd[1]: Starting containerd.service - containerd container runtime... Sep 11 00:15:21.672639 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 11 00:15:21.674450 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 11 00:15:21.678398 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 11 00:15:21.682809 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 11 00:15:21.795138 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 11 00:15:21.821919 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 11 00:15:21.844767 (ntainerd)[1530]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 11 00:15:21.864602 kernel: kvm_amd: TSC scaling supported Sep 11 00:15:21.864675 kernel: kvm_amd: Nested Virtualization enabled Sep 11 00:15:21.864716 kernel: kvm_amd: Nested Paging enabled Sep 11 00:15:21.864732 kernel: kvm_amd: LBR virtualization supported Sep 11 00:15:21.870520 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 11 00:15:21.870646 kernel: kvm_amd: Virtual GIF supported Sep 11 00:15:21.878346 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:15:21.930606 systemd-logind[1447]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 11 00:15:21.938095 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 11 00:15:21.938469 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 11 00:15:21.999838 systemd-logind[1447]: Watching system buttons on /dev/input/event2 (Power Button) Sep 11 00:15:22.023140 kernel: EDAC MC: Ver: 3.0.0 Sep 11 00:15:22.116238 sshd_keygen[1465]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 11 00:15:22.173951 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 11 00:15:22.183571 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 11 00:15:22.227470 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:15:22.240728 systemd[1]: issuegen.service: Deactivated successfully. Sep 11 00:15:22.241190 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 11 00:15:22.244909 containerd[1530]: time="2025-09-11T00:15:22Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 11 00:15:22.245480 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 11 00:15:22.246488 containerd[1530]: time="2025-09-11T00:15:22.245977179Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 11 00:15:22.266409 containerd[1530]: time="2025-09-11T00:15:22.266228550Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="518.913µs" Sep 11 00:15:22.266582 containerd[1530]: time="2025-09-11T00:15:22.266421141Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 11 00:15:22.266582 containerd[1530]: time="2025-09-11T00:15:22.266456848Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 11 00:15:22.266791 containerd[1530]: time="2025-09-11T00:15:22.266759095Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 11 00:15:22.266791 containerd[1530]: time="2025-09-11T00:15:22.266784432Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 11 00:15:22.266859 containerd[1530]: time="2025-09-11T00:15:22.266825038Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:15:22.266951 containerd[1530]: time="2025-09-11T00:15:22.266918353Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:15:22.266951 containerd[1530]: time="2025-09-11T00:15:22.266934814Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:15:22.267535 containerd[1530]: time="2025-09-11T00:15:22.267421396Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:15:22.267535 containerd[1530]: time="2025-09-11T00:15:22.267442696Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:15:22.267535 containerd[1530]: time="2025-09-11T00:15:22.267467102Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:15:22.267535 containerd[1530]: time="2025-09-11T00:15:22.267477912Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 11 00:15:22.267696 containerd[1530]: time="2025-09-11T00:15:22.267681434Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 11 00:15:22.268488 containerd[1530]: time="2025-09-11T00:15:22.268091192Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:15:22.268488 containerd[1530]: time="2025-09-11T00:15:22.268148670Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:15:22.268488 containerd[1530]: time="2025-09-11T00:15:22.268166594Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 11 00:15:22.268488 containerd[1530]: time="2025-09-11T00:15:22.268228981Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 11 00:15:22.268757 containerd[1530]: time="2025-09-11T00:15:22.268578596Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 11 00:15:22.268757 containerd[1530]: time="2025-09-11T00:15:22.268688352Z" level=info msg="metadata content store policy set" policy=shared Sep 11 00:15:22.332325 containerd[1530]: time="2025-09-11T00:15:22.332198984Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 11 00:15:22.332693 containerd[1530]: time="2025-09-11T00:15:22.332642015Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 11 00:15:22.332790 containerd[1530]: time="2025-09-11T00:15:22.332763643Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 11 00:15:22.332828 containerd[1530]: time="2025-09-11T00:15:22.332800142Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 11 00:15:22.332894 containerd[1530]: time="2025-09-11T00:15:22.332829226Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 11 00:15:22.332894 containerd[1530]: time="2025-09-11T00:15:22.332847651Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 11 00:15:22.332968 containerd[1530]: time="2025-09-11T00:15:22.332893186Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 11 00:15:22.332968 containerd[1530]: time="2025-09-11T00:15:22.332930366Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 11 00:15:22.333022 containerd[1530]: time="2025-09-11T00:15:22.332970241Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 11 00:15:22.333022 containerd[1530]: time="2025-09-11T00:15:22.332994857Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 11 00:15:22.333022 containerd[1530]: time="2025-09-11T00:15:22.333009143Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 11 00:15:22.333156 containerd[1530]: time="2025-09-11T00:15:22.333056622Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 11 00:15:22.333389 containerd[1530]: time="2025-09-11T00:15:22.333354371Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 11 00:15:22.333464 containerd[1530]: time="2025-09-11T00:15:22.333394426Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 11 00:15:22.333495 containerd[1530]: time="2025-09-11T00:15:22.333462353Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 11 00:15:22.333531 containerd[1530]: time="2025-09-11T00:15:22.333502328Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 11 00:15:22.333574 containerd[1530]: time="2025-09-11T00:15:22.333548665Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 11 00:15:22.333653 containerd[1530]: time="2025-09-11T00:15:22.333581457Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 11 00:15:22.333653 containerd[1530]: time="2025-09-11T00:15:22.333606403Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 11 00:15:22.333653 containerd[1530]: time="2025-09-11T00:15:22.333620860Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 11 00:15:22.333653 containerd[1530]: time="2025-09-11T00:15:22.333641619Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 11 00:15:22.333784 containerd[1530]: time="2025-09-11T00:15:22.333673559Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 11 00:15:22.333784 containerd[1530]: time="2025-09-11T00:15:22.333698175Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 11 00:15:22.333919 containerd[1530]: time="2025-09-11T00:15:22.333889715Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 11 00:15:22.335420 containerd[1530]: time="2025-09-11T00:15:22.333927576Z" level=info msg="Start snapshots syncer" Sep 11 00:15:22.335420 containerd[1530]: time="2025-09-11T00:15:22.333974043Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 11 00:15:22.335420 containerd[1530]: time="2025-09-11T00:15:22.334531107Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 11 00:15:22.335702 containerd[1530]: time="2025-09-11T00:15:22.334621857Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 11 00:15:22.337007 containerd[1530]: time="2025-09-11T00:15:22.336889619Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 11 00:15:22.337355 containerd[1530]: time="2025-09-11T00:15:22.337304427Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 11 00:15:22.337409 containerd[1530]: time="2025-09-11T00:15:22.337356675Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 11 00:15:22.337409 containerd[1530]: time="2025-09-11T00:15:22.337371343Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 11 00:15:22.337409 containerd[1530]: time="2025-09-11T00:15:22.337392432Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 11 00:15:22.337409 containerd[1530]: time="2025-09-11T00:15:22.337411879Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 11 00:15:22.337502 containerd[1530]: time="2025-09-11T00:15:22.337438298Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 11 00:15:22.337502 containerd[1530]: time="2025-09-11T00:15:22.337455530Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 11 00:15:22.337586 containerd[1530]: time="2025-09-11T00:15:22.337515783Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 11 00:15:22.337586 containerd[1530]: time="2025-09-11T00:15:22.337541151Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 11 00:15:22.337586 containerd[1530]: time="2025-09-11T00:15:22.337560688Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337637251Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337671806Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337682777Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337697805Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337706741Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337717431Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337733502Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337768597Z" level=info msg="runtime interface created" Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337775060Z" level=info msg="created NRI interface" Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337784447Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337809985Z" level=info msg="Connect containerd service" Sep 11 00:15:22.337890 containerd[1530]: time="2025-09-11T00:15:22.337854088Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 11 00:15:22.340737 containerd[1530]: time="2025-09-11T00:15:22.340658306Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 11 00:15:22.393905 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 11 00:15:22.397779 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 11 00:15:22.401603 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 11 00:15:22.403318 systemd[1]: Reached target getty.target - Login Prompts. Sep 11 00:15:22.491594 containerd[1530]: time="2025-09-11T00:15:22.491453857Z" level=info msg="Start subscribing containerd event" Sep 11 00:15:22.491701 containerd[1530]: time="2025-09-11T00:15:22.491561669Z" level=info msg="Start recovering state" Sep 11 00:15:22.491805 containerd[1530]: time="2025-09-11T00:15:22.491785900Z" level=info msg="Start event monitor" Sep 11 00:15:22.491805 containerd[1530]: time="2025-09-11T00:15:22.491806529Z" level=info msg="Start cni network conf syncer for default" Sep 11 00:15:22.491920 containerd[1530]: time="2025-09-11T00:15:22.491816888Z" level=info msg="Start streaming server" Sep 11 00:15:22.491920 containerd[1530]: time="2025-09-11T00:15:22.491839260Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 11 00:15:22.491920 containerd[1530]: time="2025-09-11T00:15:22.491849880Z" level=info msg="runtime interface starting up..." Sep 11 00:15:22.491920 containerd[1530]: time="2025-09-11T00:15:22.491857825Z" level=info msg="starting plugins..." Sep 11 00:15:22.491920 containerd[1530]: time="2025-09-11T00:15:22.491867373Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 11 00:15:22.492059 containerd[1530]: time="2025-09-11T00:15:22.491876430Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 11 00:15:22.492059 containerd[1530]: time="2025-09-11T00:15:22.491950068Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 11 00:15:22.492210 containerd[1530]: time="2025-09-11T00:15:22.492188435Z" level=info msg="containerd successfully booted in 0.248284s" Sep 11 00:15:22.492319 systemd[1]: Started containerd.service - containerd container runtime. Sep 11 00:15:23.280739 systemd-networkd[1435]: eth0: Gained IPv6LL Sep 11 00:15:23.284615 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 11 00:15:23.286830 systemd[1]: Reached target network-online.target - Network is Online. Sep 11 00:15:23.289949 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 11 00:15:23.292269 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 11 00:15:23.338612 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 11 00:15:23.382543 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 11 00:15:23.382983 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 11 00:15:23.386946 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 11 00:15:23.388263 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 11 00:15:23.413980 (-wrapper)[1594]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 11 00:15:23.835414 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 11 00:15:23.866849 (dockerd)[1606]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Sep 11 00:15:24.565644 dockerd[1606]: time="2025-09-11T00:15:24.565196685Z" level=info msg="Starting up" Sep 11 00:15:24.566658 dockerd[1606]: time="2025-09-11T00:15:24.566624852Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 11 00:15:24.599969 dockerd[1606]: time="2025-09-11T00:15:24.599900404Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 11 00:15:24.673530 dockerd[1606]: time="2025-09-11T00:15:24.673442616Z" level=info msg="Loading containers: start." Sep 11 00:15:24.688069 kernel: Initializing XFRM netlink socket Sep 11 00:15:25.028915 systemd-networkd[1435]: docker0: Link UP Sep 11 00:15:25.035942 dockerd[1606]: time="2025-09-11T00:15:25.035881899Z" level=info msg="Loading containers: done." Sep 11 00:15:25.055561 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3413446140-merged.mount: Deactivated successfully. Sep 11 00:15:25.058315 dockerd[1606]: time="2025-09-11T00:15:25.058241564Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Sep 11 00:15:25.058422 dockerd[1606]: time="2025-09-11T00:15:25.058394590Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 11 00:15:25.058613 dockerd[1606]: time="2025-09-11T00:15:25.058569939Z" level=info msg="Initializing buildkit" Sep 11 00:15:25.102239 dockerd[1606]: time="2025-09-11T00:15:25.102155803Z" level=info msg="Completed buildkit initialization" Sep 11 00:15:25.108238 dockerd[1606]: time="2025-09-11T00:15:25.108163337Z" level=info msg="Daemon has completed initialization" Sep 11 00:15:25.108439 dockerd[1606]: time="2025-09-11T00:15:25.108307637Z" level=info msg="API listen on /run/docker.sock" Sep 11 00:15:25.108625 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 11 00:15:25.117021 etcd-wrapper[1598]: Error response from daemon: No such container: etcd-member Sep 11 00:15:25.153855 etcd-wrapper[1808]: Error response from daemon: No such container: etcd-member Sep 11 00:15:25.197635 etcd-wrapper[1830]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Sep 11 00:15:26.314841 etcd-wrapper[1830]: v3.5.16: Pulling from coreos/etcd Sep 11 00:15:26.559293 etcd-wrapper[1830]: 804c8aba2cc6: Pulling fs layer Sep 11 00:15:26.559293 etcd-wrapper[1830]: 2ae710cd8bfe: Pulling fs layer Sep 11 00:15:26.559293 etcd-wrapper[1830]: d462aa345367: Pulling fs layer Sep 11 00:15:26.559293 etcd-wrapper[1830]: 0f8b424aa0b9: Pulling fs layer Sep 11 00:15:26.559293 etcd-wrapper[1830]: d557676654e5: Pulling fs layer Sep 11 00:15:26.559293 etcd-wrapper[1830]: c8022d07192e: Pulling fs layer Sep 11 00:15:26.559293 etcd-wrapper[1830]: d858cbc252ad: Pulling fs layer Sep 11 00:15:26.559293 etcd-wrapper[1830]: 1069fc2daed1: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: b40161cd83fc: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: 5318d93a3a65: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: 307c1adadb60: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: fbb01d9e9dc9: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: fbfea02ac3cf: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: 8c26e4bf18e2: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: 1e59a65f8816: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: ffbd4ca5f0bd: Pulling fs layer Sep 11 00:15:26.559665 etcd-wrapper[1830]: fbfea02ac3cf: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: 5318d93a3a65: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: 307c1adadb60: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: fbb01d9e9dc9: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: 0f8b424aa0b9: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: d557676654e5: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: 8c26e4bf18e2: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: ffbd4ca5f0bd: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: 1e59a65f8816: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: c8022d07192e: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: d858cbc252ad: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: 1069fc2daed1: Waiting Sep 11 00:15:26.559665 etcd-wrapper[1830]: b40161cd83fc: Waiting Sep 11 00:15:26.799616 etcd-wrapper[1830]: 2ae710cd8bfe: Download complete Sep 11 00:15:26.902453 etcd-wrapper[1830]: 804c8aba2cc6: Download complete Sep 11 00:15:26.940971 etcd-wrapper[1830]: 804c8aba2cc6: Pull complete Sep 11 00:15:26.964615 systemd[1]: var-lib-docker-overlay2-bd55e139bf45a44d6f5bf8c34ac56fc006b1617445ffbac0091e85ad2ab57f96-merged.mount: Deactivated successfully. Sep 11 00:15:26.990727 etcd-wrapper[1830]: 2ae710cd8bfe: Pull complete Sep 11 00:15:26.996587 etcd-wrapper[1830]: d462aa345367: Verifying Checksum Sep 11 00:15:26.996587 etcd-wrapper[1830]: d462aa345367: Download complete Sep 11 00:15:27.098145 etcd-wrapper[1830]: 0f8b424aa0b9: Verifying Checksum Sep 11 00:15:27.098145 etcd-wrapper[1830]: 0f8b424aa0b9: Download complete Sep 11 00:15:27.157592 etcd-wrapper[1830]: d557676654e5: Verifying Checksum Sep 11 00:15:27.157592 etcd-wrapper[1830]: d557676654e5: Download complete Sep 11 00:15:27.307183 etcd-wrapper[1830]: c8022d07192e: Verifying Checksum Sep 11 00:15:27.307183 etcd-wrapper[1830]: c8022d07192e: Download complete Sep 11 00:15:27.410000 etcd-wrapper[1830]: d858cbc252ad: Verifying Checksum Sep 11 00:15:27.410000 etcd-wrapper[1830]: d858cbc252ad: Download complete Sep 11 00:15:27.472315 etcd-wrapper[1830]: 1069fc2daed1: Verifying Checksum Sep 11 00:15:27.472315 etcd-wrapper[1830]: 1069fc2daed1: Download complete Sep 11 00:15:27.533880 etcd-wrapper[1830]: d462aa345367: Pull complete Sep 11 00:15:27.559010 etcd-wrapper[1830]: 0f8b424aa0b9: Pull complete Sep 11 00:15:27.563331 etcd-wrapper[1830]: b40161cd83fc: Verifying Checksum Sep 11 00:15:27.563331 etcd-wrapper[1830]: b40161cd83fc: Download complete Sep 11 00:15:27.584500 etcd-wrapper[1830]: d557676654e5: Pull complete Sep 11 00:15:27.618408 etcd-wrapper[1830]: c8022d07192e: Pull complete Sep 11 00:15:27.645246 etcd-wrapper[1830]: d858cbc252ad: Pull complete Sep 11 00:15:27.665928 etcd-wrapper[1830]: 5318d93a3a65: Verifying Checksum Sep 11 00:15:27.665928 etcd-wrapper[1830]: 5318d93a3a65: Download complete Sep 11 00:15:27.676626 etcd-wrapper[1830]: 1069fc2daed1: Pull complete Sep 11 00:15:27.701349 etcd-wrapper[1830]: b40161cd83fc: Pull complete Sep 11 00:15:27.732660 etcd-wrapper[1830]: 5318d93a3a65: Pull complete Sep 11 00:15:27.824910 etcd-wrapper[1830]: 307c1adadb60: Verifying Checksum Sep 11 00:15:27.824910 etcd-wrapper[1830]: 307c1adadb60: Download complete Sep 11 00:15:27.855244 etcd-wrapper[1830]: 307c1adadb60: Pull complete Sep 11 00:15:27.941099 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 11 00:15:27.942734 systemd[1]: Started sshd@0-10.0.0.54:22-10.0.0.1:51728.service - OpenSSH per-connection server daemon (10.0.0.1:51728). Sep 11 00:15:27.956106 systemd[1]: var-lib-docker-overlay2-b792173879336952bc16a07e78435c83deb84a3549278cd0f0c6863de349afdf-merged.mount: Deactivated successfully. Sep 11 00:15:28.147897 etcd-wrapper[1830]: fbfea02ac3cf: Verifying Checksum Sep 11 00:15:28.147897 etcd-wrapper[1830]: fbfea02ac3cf: Download complete Sep 11 00:15:28.158789 sshd[1902]: Accepted publickey for core from 10.0.0.1 port 51728 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:28.160991 sshd-session[1902]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:28.168803 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 11 00:15:28.170216 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 11 00:15:28.177912 systemd-logind[1447]: New session 1 of user core. Sep 11 00:15:28.197971 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 11 00:15:28.203370 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 11 00:15:28.217427 (systemd)[1907]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 11 00:15:28.219422 etcd-wrapper[1830]: fbb01d9e9dc9: Verifying Checksum Sep 11 00:15:28.219422 etcd-wrapper[1830]: fbb01d9e9dc9: Download complete Sep 11 00:15:28.221467 systemd-logind[1447]: New session c1 of user core. Sep 11 00:15:28.384591 systemd[1907]: Queued start job for default target default.target. Sep 11 00:15:28.813881 systemd[1907]: Created slice app.slice - User Application Slice. Sep 11 00:15:28.813922 systemd[1907]: Reached target paths.target - Paths. Sep 11 00:15:28.813981 systemd[1907]: Reached target timers.target - Timers. Sep 11 00:15:28.816475 systemd[1907]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:15:28.837267 systemd[1907]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:15:28.837459 systemd[1907]: Reached target sockets.target - Sockets. Sep 11 00:15:28.837506 systemd[1907]: Reached target basic.target - Basic System. Sep 11 00:15:28.837551 systemd[1907]: Reached target default.target - Main User Target. Sep 11 00:15:28.837588 systemd[1907]: Startup finished in 606ms. Sep 11 00:15:28.837968 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 11 00:15:28.842297 etcd-wrapper[1830]: 1e59a65f8816: Verifying Checksum Sep 11 00:15:28.842297 etcd-wrapper[1830]: 1e59a65f8816: Download complete Sep 11 00:15:28.845209 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 11 00:15:28.887225 systemd[1]: var-lib-docker-overlay2-8975f1e5d46489e6ca46fe3f2ac067b5f248dd152e2089c29a639514b7eae3d4-merged.mount: Deactivated successfully. Sep 11 00:15:28.914113 systemd[1]: Started sshd@1-10.0.0.54:22-10.0.0.1:51738.service - OpenSSH per-connection server daemon (10.0.0.1:51738). Sep 11 00:15:29.222289 sshd[1923]: Accepted publickey for core from 10.0.0.1 port 51738 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:29.222791 sshd-session[1923]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:29.224383 etcd-wrapper[1830]: ffbd4ca5f0bd: Verifying Checksum Sep 11 00:15:29.224383 etcd-wrapper[1830]: ffbd4ca5f0bd: Download complete Sep 11 00:15:29.232557 etcd-wrapper[1830]: 8c26e4bf18e2: Verifying Checksum Sep 11 00:15:29.232557 etcd-wrapper[1830]: 8c26e4bf18e2: Download complete Sep 11 00:15:29.235341 systemd-logind[1447]: New session 2 of user core. Sep 11 00:15:29.241308 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 11 00:15:29.298093 sshd[1927]: Connection closed by 10.0.0.1 port 51738 Sep 11 00:15:29.298509 sshd-session[1923]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:29.313692 systemd[1]: sshd@1-10.0.0.54:22-10.0.0.1:51738.service: Deactivated successfully. Sep 11 00:15:29.316187 systemd[1]: session-2.scope: Deactivated successfully. Sep 11 00:15:29.317064 systemd-logind[1447]: Session 2 logged out. Waiting for processes to exit. Sep 11 00:15:29.320411 systemd[1]: Started sshd@2-10.0.0.54:22-10.0.0.1:51746.service - OpenSSH per-connection server daemon (10.0.0.1:51746). Sep 11 00:15:29.321176 systemd-logind[1447]: Removed session 2. Sep 11 00:15:29.375494 etcd-wrapper[1830]: fbb01d9e9dc9: Pull complete Sep 11 00:15:29.386824 sshd[1933]: Accepted publickey for core from 10.0.0.1 port 51746 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:29.388981 sshd-session[1933]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:29.399119 systemd-logind[1447]: New session 3 of user core. Sep 11 00:15:29.413395 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 11 00:15:29.473449 sshd[1941]: Connection closed by 10.0.0.1 port 51746 Sep 11 00:15:29.474437 sshd-session[1933]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:29.480099 systemd-logind[1447]: Session 3 logged out. Waiting for processes to exit. Sep 11 00:15:29.480438 systemd[1]: sshd@2-10.0.0.54:22-10.0.0.1:51746.service: Deactivated successfully. Sep 11 00:15:29.483419 systemd[1]: session-3.scope: Deactivated successfully. Sep 11 00:15:29.487334 systemd-logind[1447]: Removed session 3. Sep 11 00:15:29.524291 systemd[1]: var-lib-docker-overlay2-7790ce0401f25504e3983be949dd1e6e4b7dbbaaa36ab4e86244f35fdf5ddc6e-merged.mount: Deactivated successfully. Sep 11 00:15:29.621190 etcd-wrapper[1830]: fbfea02ac3cf: Pull complete Sep 11 00:15:29.719300 systemd[1]: var-lib-docker-overlay2-00926313f0797431193027ced7550bb2899c6622335f0773fd798242144091af-merged.mount: Deactivated successfully. Sep 11 00:15:29.787539 etcd-wrapper[1830]: 8c26e4bf18e2: Pull complete Sep 11 00:15:29.826181 etcd-wrapper[1830]: 1e59a65f8816: Pull complete Sep 11 00:15:29.858714 etcd-wrapper[1830]: ffbd4ca5f0bd: Pull complete Sep 11 00:15:29.873160 etcd-wrapper[1830]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Sep 11 00:15:29.878278 etcd-wrapper[1830]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Sep 11 00:15:30.168996 containerd[1530]: time="2025-09-11T00:15:30.168838682Z" level=info msg="connecting to shim 3f1a405721a4ede736e38edf5c0f2bcb6b83c1626635947e574c20b567cb0ec4" address="unix:///run/containerd/s/59a19c9102f8c564dd9c4239157dc5205965dbf2bebceb913e266254d613e9fa" namespace=moby protocol=ttrpc version=3 Sep 11 00:15:30.212451 systemd[1]: Started docker-3f1a405721a4ede736e38edf5c0f2bcb6b83c1626635947e574c20b567cb0ec4.scope - libcontainer container 3f1a405721a4ede736e38edf5c0f2bcb6b83c1626635947e574c20b567cb0ec4. Sep 11 00:15:30.230241 systemd-resolved[1350]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.317463Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.317665Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"9a896827bfea4ec5bf1f12510f20db3f"} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:15:30.317694Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:15:30.317722Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:15:30.317729Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:15:30.317734Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:15:30.317760Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.317802Z","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.54:2379"]} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:15:30.317895Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 11 00:15:30.318108 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.317913Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Sep 11 00:15:30.318748 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.318548Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 11 00:15:30.318933 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.318708Z","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":"9a896827bfea4ec5bf1f12510f20db3f","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.54:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"9a896827bfea4ec5bf1f12510f20db3f=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Sep 11 00:15:30.322338 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.322073Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.889979ms"} Sep 11 00:15:30.329420 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.328990Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Sep 11 00:15:30.329420 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.329286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Sep 11 00:15:30.329552 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.329343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Sep 11 00:15:30.329552 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.329406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Sep 11 00:15:30.329552 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.329455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Sep 11 00:15:30.329629 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.329520Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 11 00:15:30.335269 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:15:30.334995Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Sep 11 00:15:30.337299 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.337114Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Sep 11 00:15:30.338351 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.338119Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Sep 11 00:15:30.339533 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.339118Z","caller":"etcdserver/server.go:873","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.16","cluster-version":"to_be_decided"} Sep 11 00:15:30.339643 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.339446Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 11 00:15:30.340177 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.339879Z","caller":"etcdserver/server.go:757","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Sep 11 00:15:30.340177 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.339972Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Sep 11 00:15:30.340177 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.340011Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Sep 11 00:15:30.340177 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.340020Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Sep 11 00:15:30.340787 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.340534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 11 00:15:30.341639 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.340696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} Sep 11 00:15:30.341639 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.341461Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Sep 11 00:15:30.341639 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.341508Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Sep 11 00:15:30.341849 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.341514Z","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.54:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 11 00:15:30.393075 systemd[1]: var-lib-docker-overlay2-af10890dabf4e31811eed1b8244383bab79ff8a74c50f15871a8441df65eef47-merged.mount: Deactivated successfully. Sep 11 00:15:30.931053 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.930224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Sep 11 00:15:30.931053 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.930299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Sep 11 00:15:30.931053 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.930370Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Sep 11 00:15:30.931053 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.930393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Sep 11 00:15:30.931053 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.930414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Sep 11 00:15:30.931053 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.930428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Sep 11 00:15:30.931053 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.930444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Sep 11 00:15:30.932060 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.931706Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Sep 11 00:15:30.933151 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.932665Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:9a896827bfea4ec5bf1f12510f20db3f ClientURLs:[http://10.0.0.54:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Sep 11 00:15:30.933151 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.932726Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Sep 11 00:15:30.933557 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.932904Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Sep 11 00:15:30.933557 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.933010Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Sep 11 00:15:30.933557 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.933136Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 11 00:15:30.933557 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.933168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Sep 11 00:15:30.933557 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.933330Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Sep 11 00:15:30.933761 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 11 00:15:30.934268 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.934047Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 11 00:15:30.934456 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 11 00:15:30.934722 systemd[1]: Startup finished in 3.434s (kernel) + 5.009s (initrd) + 12.270s (userspace) = 20.715s. Sep 11 00:15:30.934885 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:15:30.934464Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Sep 11 00:15:39.485634 systemd[1]: Started sshd@3-10.0.0.54:22-10.0.0.1:39162.service - OpenSSH per-connection server daemon (10.0.0.1:39162). Sep 11 00:15:39.540580 sshd[2017]: Accepted publickey for core from 10.0.0.1 port 39162 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:39.542127 sshd-session[2017]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:39.546561 systemd-logind[1447]: New session 4 of user core. Sep 11 00:15:39.556173 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 11 00:15:39.609055 sshd[2020]: Connection closed by 10.0.0.1 port 39162 Sep 11 00:15:39.609424 sshd-session[2017]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:39.620523 systemd[1]: sshd@3-10.0.0.54:22-10.0.0.1:39162.service: Deactivated successfully. Sep 11 00:15:39.622162 systemd[1]: session-4.scope: Deactivated successfully. Sep 11 00:15:39.622835 systemd-logind[1447]: Session 4 logged out. Waiting for processes to exit. Sep 11 00:15:39.625210 systemd[1]: Started sshd@4-10.0.0.54:22-10.0.0.1:39166.service - OpenSSH per-connection server daemon (10.0.0.1:39166). Sep 11 00:15:39.625731 systemd-logind[1447]: Removed session 4. Sep 11 00:15:39.686837 sshd[2026]: Accepted publickey for core from 10.0.0.1 port 39166 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:39.688171 sshd-session[2026]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:39.692414 systemd-logind[1447]: New session 5 of user core. Sep 11 00:15:39.702234 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 11 00:15:39.752297 sshd[2029]: Connection closed by 10.0.0.1 port 39166 Sep 11 00:15:39.752599 sshd-session[2026]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:39.765763 systemd[1]: sshd@4-10.0.0.54:22-10.0.0.1:39166.service: Deactivated successfully. Sep 11 00:15:39.767577 systemd[1]: session-5.scope: Deactivated successfully. Sep 11 00:15:39.768357 systemd-logind[1447]: Session 5 logged out. Waiting for processes to exit. Sep 11 00:15:39.770922 systemd[1]: Started sshd@5-10.0.0.54:22-10.0.0.1:39174.service - OpenSSH per-connection server daemon (10.0.0.1:39174). Sep 11 00:15:39.771884 systemd-logind[1447]: Removed session 5. Sep 11 00:15:39.826590 sshd[2035]: Accepted publickey for core from 10.0.0.1 port 39174 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:39.828050 sshd-session[2035]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:39.832799 systemd-logind[1447]: New session 6 of user core. Sep 11 00:15:39.842318 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 11 00:15:39.896387 sshd[2038]: Connection closed by 10.0.0.1 port 39174 Sep 11 00:15:39.896792 sshd-session[2035]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:39.911433 systemd[1]: sshd@5-10.0.0.54:22-10.0.0.1:39174.service: Deactivated successfully. Sep 11 00:15:39.913439 systemd[1]: session-6.scope: Deactivated successfully. Sep 11 00:15:39.914205 systemd-logind[1447]: Session 6 logged out. Waiting for processes to exit. Sep 11 00:15:39.916924 systemd[1]: Started sshd@6-10.0.0.54:22-10.0.0.1:49138.service - OpenSSH per-connection server daemon (10.0.0.1:49138). Sep 11 00:15:39.917809 systemd-logind[1447]: Removed session 6. Sep 11 00:15:39.973456 sshd[2044]: Accepted publickey for core from 10.0.0.1 port 49138 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:39.974880 sshd-session[2044]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:39.979522 systemd-logind[1447]: New session 7 of user core. Sep 11 00:15:39.994223 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 11 00:15:40.054297 sudo[2048]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 11 00:15:40.054703 sudo[2048]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:15:40.078959 sudo[2048]: pam_unix(sudo:session): session closed for user root Sep 11 00:15:40.080827 sshd[2047]: Connection closed by 10.0.0.1 port 49138 Sep 11 00:15:40.081622 sshd-session[2044]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:40.095849 systemd[1]: sshd@6-10.0.0.54:22-10.0.0.1:49138.service: Deactivated successfully. Sep 11 00:15:40.097798 systemd[1]: session-7.scope: Deactivated successfully. Sep 11 00:15:40.098640 systemd-logind[1447]: Session 7 logged out. Waiting for processes to exit. Sep 11 00:15:40.101652 systemd[1]: Started sshd@7-10.0.0.54:22-10.0.0.1:49140.service - OpenSSH per-connection server daemon (10.0.0.1:49140). Sep 11 00:15:40.102521 systemd-logind[1447]: Removed session 7. Sep 11 00:15:40.169956 sshd[2054]: Accepted publickey for core from 10.0.0.1 port 49140 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:40.172012 sshd-session[2054]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:40.177358 systemd-logind[1447]: New session 8 of user core. Sep 11 00:15:40.188184 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 11 00:15:40.243202 sudo[2059]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 11 00:15:40.243576 sudo[2059]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:15:40.317724 sudo[2059]: pam_unix(sudo:session): session closed for user root Sep 11 00:15:40.328299 sudo[2058]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 11 00:15:40.328675 sudo[2058]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:15:40.342492 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:15:40.399786 augenrules[2081]: No rules Sep 11 00:15:40.402014 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:15:40.402496 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:15:40.403851 sudo[2058]: pam_unix(sudo:session): session closed for user root Sep 11 00:15:40.405655 sshd[2057]: Connection closed by 10.0.0.1 port 49140 Sep 11 00:15:40.406106 sshd-session[2054]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:40.413945 systemd[1]: sshd@7-10.0.0.54:22-10.0.0.1:49140.service: Deactivated successfully. Sep 11 00:15:40.415883 systemd[1]: session-8.scope: Deactivated successfully. Sep 11 00:15:40.416726 systemd-logind[1447]: Session 8 logged out. Waiting for processes to exit. Sep 11 00:15:40.419545 systemd[1]: Started sshd@8-10.0.0.54:22-10.0.0.1:49154.service - OpenSSH per-connection server daemon (10.0.0.1:49154). Sep 11 00:15:40.420317 systemd-logind[1447]: Removed session 8. Sep 11 00:15:40.474491 sshd[2090]: Accepted publickey for core from 10.0.0.1 port 49154 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:40.476480 sshd-session[2090]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:40.482021 systemd-logind[1447]: New session 9 of user core. Sep 11 00:15:40.497248 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 11 00:15:40.549907 sshd[2093]: Connection closed by 10.0.0.1 port 49154 Sep 11 00:15:40.550307 sshd-session[2090]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:40.559811 systemd[1]: sshd@8-10.0.0.54:22-10.0.0.1:49154.service: Deactivated successfully. Sep 11 00:15:40.561638 systemd[1]: session-9.scope: Deactivated successfully. Sep 11 00:15:40.562481 systemd-logind[1447]: Session 9 logged out. Waiting for processes to exit. Sep 11 00:15:40.565265 systemd[1]: Started sshd@9-10.0.0.54:22-10.0.0.1:49162.service - OpenSSH per-connection server daemon (10.0.0.1:49162). Sep 11 00:15:40.565941 systemd-logind[1447]: Removed session 9. Sep 11 00:15:40.622073 sshd[2101]: Accepted publickey for core from 10.0.0.1 port 49162 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:15:40.623906 sshd-session[2101]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:15:40.629196 systemd-logind[1447]: New session 10 of user core. Sep 11 00:15:40.640213 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 11 00:15:40.818999 sshd[2104]: Connection closed by 10.0.0.1 port 49162 Sep 11 00:15:40.819386 sshd-session[2101]: pam_unix(sshd:session): session closed for user core Sep 11 00:15:40.824455 systemd[1]: sshd@9-10.0.0.54:22-10.0.0.1:49162.service: Deactivated successfully. Sep 11 00:15:40.826352 systemd[1]: session-10.scope: Deactivated successfully. Sep 11 00:15:40.827199 systemd-logind[1447]: Session 10 logged out. Waiting for processes to exit. Sep 11 00:15:40.828470 systemd-logind[1447]: Removed session 10. Sep 11 00:16:06.224346 update_engine[1448]: I20250911 00:16:06.224169 1448 update_attempter.cc:509] Updating boot flags... Sep 11 00:16:44.553284 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.552579Z","caller":"traceutil/trace.go:171","msg":"trace[1530755179] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"147.823793ms","start":"2025-09-11T00:16:44.404728Z","end":"2025-09-11T00:16:44.552552Z","steps":["trace[1530755179] 'process raft request' (duration: 51.880995ms)","trace[1530755179] 'compare' (duration: 95.824887ms)"],"step_count":2} Sep 11 00:16:44.553284 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.552573Z","caller":"traceutil/trace.go:171","msg":"trace[1183220425] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:5; }","duration":"134.888277ms","start":"2025-09-11T00:16:44.417654Z","end":"2025-09-11T00:16:44.552542Z","steps":["trace[1183220425] 'read index received' (duration: 4.206744ms)","trace[1183220425] 'applied index is now lower than readState.Index' (duration: 130.680912ms)"],"step_count":2} Sep 11 00:16:44.553284 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.552712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.031846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/localhost\" ","response":"range_response_count:0 size:4"} Sep 11 00:16:44.553284 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.552768Z","caller":"traceutil/trace.go:171","msg":"trace[917792596] range","detail":"{range_begin:/registry/csinodes/localhost; range_end:; response_count:0; response_revision:3; }","duration":"135.120122ms","start":"2025-09-11T00:16:44.417633Z","end":"2025-09-11T00:16:44.552753Z","steps":["trace[917792596] 'agreement among raft nodes before linearized reading' (duration: 135.004014ms)"],"step_count":1} Sep 11 00:16:44.889508 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.888790Z","caller":"traceutil/trace.go:171","msg":"trace[1807002679] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"299.305255ms","start":"2025-09-11T00:16:44.589463Z","end":"2025-09-11T00:16:44.888768Z","steps":["trace[1807002679] 'process raft request' (duration: 266.889859ms)","trace[1807002679] 'compare' (duration: 32.259543ms)"],"step_count":2} Sep 11 00:16:44.889508 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.888878Z","caller":"traceutil/trace.go:171","msg":"trace[1367016956] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"299.402457ms","start":"2025-09-11T00:16:44.589471Z","end":"2025-09-11T00:16:44.888873Z","steps":["trace[1367016956] 'process raft request' (duration: 299.25975ms)"],"step_count":1} Sep 11 00:16:44.889508 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.888882Z","caller":"traceutil/trace.go:171","msg":"trace[86821973] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:8; }","duration":"298.649925ms","start":"2025-09-11T00:16:44.590221Z","end":"2025-09-11T00:16:44.888871Z","steps":["trace[86821973] 'read index received' (duration: 31.58641ms)","trace[86821973] 'applied index is now lower than readState.Index' (duration: 267.062914ms)"],"step_count":2} Sep 11 00:16:44.889508 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.888852Z","caller":"traceutil/trace.go:171","msg":"trace[1989312382] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"299.296167ms","start":"2025-09-11T00:16:44.589522Z","end":"2025-09-11T00:16:44.888818Z","steps":["trace[1989312382] 'process raft request' (duration: 299.226887ms)"],"step_count":1} Sep 11 00:16:44.889872 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.888921Z","caller":"traceutil/trace.go:171","msg":"trace[1463705644] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"299.360027ms","start":"2025-09-11T00:16:44.589555Z","end":"2025-09-11T00:16:44.888915Z","steps":["trace[1463705644] 'process raft request' (duration: 299.20711ms)"],"step_count":1} Sep 11 00:16:44.889872 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.888935Z","caller":"traceutil/trace.go:171","msg":"trace[791047731] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"299.419258ms","start":"2025-09-11T00:16:44.589506Z","end":"2025-09-11T00:16:44.888925Z","steps":["trace[791047731] 'process raft request' (duration: 299.275829ms)"],"step_count":1} Sep 11 00:16:44.889872 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.888908Z","caller":"traceutil/trace.go:171","msg":"trace[1521826598] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"298.936142ms","start":"2025-09-11T00:16:44.589967Z","end":"2025-09-11T00:16:44.888904Z","steps":["trace[1521826598] 'process raft request' (duration: 298.836395ms)"],"step_count":1} Sep 11 00:16:44.889872 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.889049Z","caller":"traceutil/trace.go:171","msg":"trace[1323336835] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"297.11295ms","start":"2025-09-11T00:16:44.591912Z","end":"2025-09-11T00:16:44.889025Z","steps":["trace[1323336835] 'process raft request' (duration: 296.912965ms)"],"step_count":1} Sep 11 00:16:44.889872 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.889061Z","caller":"traceutil/trace.go:171","msg":"trace[88946439] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"275.396493ms","start":"2025-09-11T00:16:44.613658Z","end":"2025-09-11T00:16:44.889054Z","steps":["trace[88946439] 'process raft request' (duration: 275.187462ms)"],"step_count":1} Sep 11 00:16:44.890104 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.889213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.969834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} Sep 11 00:16:44.890104 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.889258Z","caller":"traceutil/trace.go:171","msg":"trace[738862266] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:14; }","duration":"299.040738ms","start":"2025-09-11T00:16:44.590204Z","end":"2025-09-11T00:16:44.889245Z","steps":["trace[738862266] 'agreement among raft nodes before linearized reading' (duration: 298.867042ms)"],"step_count":1} Sep 11 00:16:44.940569 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.939952Z","caller":"traceutil/trace.go:171","msg":"trace[1179062704] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"315.147333ms","start":"2025-09-11T00:16:44.624785Z","end":"2025-09-11T00:16:44.939933Z","steps":["trace[1179062704] 'process raft request' (duration: 314.984658ms)"],"step_count":1} Sep 11 00:16:44.942977 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.940480Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.261872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} Sep 11 00:16:44.942977 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.940560Z","caller":"traceutil/trace.go:171","msg":"trace[1316828117] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:15; }","duration":"317.367801ms","start":"2025-09-11T00:16:44.623175Z","end":"2025-09-11T00:16:44.940543Z","steps":["trace[1316828117] 'agreement among raft nodes before linearized reading' (duration: 316.831625ms)"],"step_count":1} Sep 11 00:16:44.942977 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.940582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:44.623165Z","time spent":"317.409229ms","remote":"10.0.0.58:34684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} Sep 11 00:16:44.942977 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.940664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.110612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests\" limit:1 ","response":"range_response_count:0 size:4"} Sep 11 00:16:44.942977 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.940681Z","caller":"traceutil/trace.go:171","msg":"trace[204759876] range","detail":"{range_begin:/registry/certificatesigningrequests; range_end:; response_count:0; response_revision:15; }","duration":"316.139105ms","start":"2025-09-11T00:16:44.624535Z","end":"2025-09-11T00:16:44.940675Z","steps":["trace[204759876] 'agreement among raft nodes before linearized reading' (duration: 316.10414ms)"],"step_count":1} Sep 11 00:16:44.943210 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.940703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:44.624528Z","time spent":"316.169222ms","remote":"10.0.0.58:34844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":28,"request content":"key:\"/registry/certificatesigningrequests\" limit:1 "} Sep 11 00:16:44.943210 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.940837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.169533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/localhost\" ","response":"range_response_count:0 size:4"} Sep 11 00:16:44.943210 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:44.940865Z","caller":"traceutil/trace.go:171","msg":"trace[1271730628] range","detail":"{range_begin:/registry/csinodes/localhost; range_end:; response_count:0; response_revision:15; }","duration":"331.204788ms","start":"2025-09-11T00:16:44.609648Z","end":"2025-09-11T00:16:44.940853Z","steps":["trace[1271730628] 'agreement among raft nodes before linearized reading' (duration: 331.174171ms)"],"step_count":1} Sep 11 00:16:44.943210 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.940881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:44.609642Z","time spent":"331.234244ms","remote":"10.0.0.58:34966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/localhost\" "} Sep 11 00:16:44.943329 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:44.941365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:44.624776Z","time spent":"315.237974ms","remote":"10.0.0.58:35028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.141634 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.141149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.037573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} Sep 11 00:16:45.142293 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141275Z","caller":"traceutil/trace.go:171","msg":"trace[2137961418] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"248.445138ms","start":"2025-09-11T00:16:44.892811Z","end":"2025-09-11T00:16:45.141256Z","steps":["trace[2137961418] 'process raft request' (duration: 104.092633ms)","trace[2137961418] 'compare' (duration: 143.92904ms)"],"step_count":2} Sep 11 00:16:45.142293 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141394Z","caller":"traceutil/trace.go:171","msg":"trace[1635756474] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"194.616957ms","start":"2025-09-11T00:16:44.946770Z","end":"2025-09-11T00:16:45.141387Z","steps":["trace[1635756474] 'process raft request' (duration: 194.597571ms)"],"step_count":1} Sep 11 00:16:45.142293 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141424Z","caller":"traceutil/trace.go:171","msg":"trace[15035551] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"247.01298ms","start":"2025-09-11T00:16:44.894401Z","end":"2025-09-11T00:16:45.141414Z","steps":["trace[15035551] 'process raft request' (duration: 246.915247ms)"],"step_count":1} Sep 11 00:16:45.142293 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141459Z","caller":"traceutil/trace.go:171","msg":"trace[1251953066] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"247.287996ms","start":"2025-09-11T00:16:44.894156Z","end":"2025-09-11T00:16:45.141444Z","steps":["trace[1251953066] 'process raft request' (duration: 247.098039ms)"],"step_count":1} Sep 11 00:16:45.142494 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141469Z","caller":"traceutil/trace.go:171","msg":"trace[536595834] linearizableReadLoop","detail":"{readStateIndex:26; appliedIndex:20; }","duration":"201.596777ms","start":"2025-09-11T00:16:44.939865Z","end":"2025-09-11T00:16:45.141462Z","steps":["trace[536595834] 'read index received' (duration: 57.026244ms)","trace[536595834] 'applied index is now lower than readState.Index' (duration: 144.569932ms)"],"step_count":2} Sep 11 00:16:45.142494 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141489Z","caller":"traceutil/trace.go:171","msg":"trace[1579045004] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"247.128566ms","start":"2025-09-11T00:16:44.894355Z","end":"2025-09-11T00:16:45.141483Z","steps":["trace[1579045004] 'process raft request' (duration: 246.913032ms)"],"step_count":1} Sep 11 00:16:45.142494 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141515Z","caller":"traceutil/trace.go:171","msg":"trace[1751497235] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"247.124529ms","start":"2025-09-11T00:16:44.894386Z","end":"2025-09-11T00:16:45.141510Z","steps":["trace[1751497235] 'process raft request' (duration: 246.917791ms)"],"step_count":1} Sep 11 00:16:45.142494 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141529Z","caller":"traceutil/trace.go:171","msg":"trace[165897380] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"248.639902ms","start":"2025-09-11T00:16:44.892882Z","end":"2025-09-11T00:16:45.141522Z","steps":["trace[165897380] 'process raft request' (duration: 248.343456ms)"],"step_count":1} Sep 11 00:16:45.142494 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141561Z","caller":"traceutil/trace.go:171","msg":"trace[893970484] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"195.216742ms","start":"2025-09-11T00:16:44.946338Z","end":"2025-09-11T00:16:45.141555Z","steps":["trace[893970484] 'process raft request' (duration: 195.007239ms)"],"step_count":1} Sep 11 00:16:45.142652 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.141663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.043509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" ","response":"range_response_count:1 size:2930"} Sep 11 00:16:45.142652 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141684Z","caller":"traceutil/trace.go:171","msg":"trace[834926947] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:23; }","duration":"243.0703ms","start":"2025-09-11T00:16:44.898605Z","end":"2025-09-11T00:16:45.141676Z","steps":["trace[834926947] 'agreement among raft nodes before linearized reading' (duration: 242.986523ms)"],"step_count":1} Sep 11 00:16:45.142652 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.141767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.604064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/localhost\" ","response":"range_response_count:1 size:2930"} Sep 11 00:16:45.142652 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.141829Z","caller":"traceutil/trace.go:171","msg":"trace[1909320954] range","detail":"{range_begin:/registry/minions/localhost; range_end:; response_count:1; response_revision:23; }","duration":"197.649009ms","start":"2025-09-11T00:16:44.944140Z","end":"2025-09-11T00:16:45.141789Z","steps":["trace[1909320954] 'agreement among raft nodes before linearized reading' (duration: 197.456647ms)"],"step_count":1} Sep 11 00:16:45.462000 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.461233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.710779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} Sep 11 00:16:45.462000 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461414Z","caller":"traceutil/trace.go:171","msg":"trace[1844280615] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"315.004002ms","start":"2025-09-11T00:16:45.146360Z","end":"2025-09-11T00:16:45.461364Z","steps":["trace[1844280615] 'process raft request' (duration: 103.117482ms)","trace[1844280615] 'compare' (duration: 211.521514ms)"],"step_count":2} Sep 11 00:16:45.462000 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461473Z","caller":"traceutil/trace.go:171","msg":"trace[1055028381] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"314.997309ms","start":"2025-09-11T00:16:45.146459Z","end":"2025-09-11T00:16:45.461456Z","steps":["trace[1055028381] 'process raft request' (duration: 314.858437ms)"],"step_count":1} Sep 11 00:16:45.462000 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.461556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.146450Z","time spent":"315.075135ms","remote":"10.0.0.58:35130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.462694 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.461598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.146350Z","time spent":"315.155596ms","remote":"10.0.0.58:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.462694 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461638Z","caller":"traceutil/trace.go:171","msg":"trace[358726503] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"314.913301ms","start":"2025-09-11T00:16:45.146718Z","end":"2025-09-11T00:16:45.461631Z","steps":["trace[358726503] 'process raft request' (duration: 314.661338ms)"],"step_count":1} Sep 11 00:16:45.462694 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.461673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.146694Z","time spent":"314.964126ms","remote":"10.0.0.58:35028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":703,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.462694 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461692Z","caller":"traceutil/trace.go:171","msg":"trace[65299496] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"313.344386ms","start":"2025-09-11T00:16:45.148340Z","end":"2025-09-11T00:16:45.461685Z","steps":["trace[65299496] 'process raft request' (duration: 313.111149ms)"],"step_count":1} Sep 11 00:16:45.462839 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.461727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.148334Z","time spent":"313.375345ms","remote":"10.0.0.58:35130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.462839 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461788Z","caller":"traceutil/trace.go:171","msg":"trace[1004331872] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"314.227453ms","start":"2025-09-11T00:16:45.147554Z","end":"2025-09-11T00:16:45.461782Z","steps":["trace[1004331872] 'process raft request' (duration: 313.844495ms)"],"step_count":1} Sep 11 00:16:45.462839 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461832Z","caller":"traceutil/trace.go:171","msg":"trace[743122262] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"304.712067ms","start":"2025-09-11T00:16:45.157114Z","end":"2025-09-11T00:16:45.461826Z","steps":["trace[743122262] 'process raft request' (duration: 304.376187ms)"],"step_count":1} Sep 11 00:16:45.462839 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.461858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.147545Z","time spent":"314.29499ms","remote":"10.0.0.58:35130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.463008 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461889Z","caller":"traceutil/trace.go:171","msg":"trace[1631245670] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"314.117587ms","start":"2025-09-11T00:16:45.147766Z","end":"2025-09-11T00:16:45.461883Z","steps":["trace[1631245670] 'process raft request' (duration: 313.647406ms)"],"step_count":1} Sep 11 00:16:45.463008 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.461934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.147741Z","time spent":"314.160498ms","remote":"10.0.0.58:35130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.463008 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.461984Z","caller":"traceutil/trace.go:171","msg":"trace[1778605375] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"314.050031ms","start":"2025-09-11T00:16:45.147927Z","end":"2025-09-11T00:16:45.461977Z","steps":["trace[1778605375] 'process raft request' (duration: 313.497894ms)"],"step_count":1} Sep 11 00:16:45.463008 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.462012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.147920Z","time spent":"314.076901ms","remote":"10.0.0.58:34684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":326,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} Sep 11 00:16:45.463200 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.462111Z","caller":"traceutil/trace.go:171","msg":"trace[1494273408] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"259.076647ms","start":"2025-09-11T00:16:45.203026Z","end":"2025-09-11T00:16:45.462103Z","steps":["trace[1494273408] 'process raft request' (duration: 258.541322ms)"],"step_count":1} Sep 11 00:16:45.463200 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.462371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:16:45.157094Z","time spent":"304.76729ms","remote":"10.0.0.58:34752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3238,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} Sep 11 00:16:45.734420 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.733447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.210204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} Sep 11 00:16:45.734420 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733598Z","caller":"traceutil/trace.go:171","msg":"trace[1278746040] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"267.162391ms","start":"2025-09-11T00:16:45.466418Z","end":"2025-09-11T00:16:45.733580Z","steps":["trace[1278746040] 'process raft request' (duration: 148.76262ms)","trace[1278746040] 'compare' (duration: 118.063279ms)"],"step_count":2} Sep 11 00:16:45.734420 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733601Z","caller":"traceutil/trace.go:171","msg":"trace[1756916743] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"267.175486ms","start":"2025-09-11T00:16:45.466417Z","end":"2025-09-11T00:16:45.733592Z","steps":["trace[1756916743] 'process raft request' (duration: 267.106407ms)"],"step_count":1} Sep 11 00:16:45.734420 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733678Z","caller":"traceutil/trace.go:171","msg":"trace[1775650440] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"264.596206ms","start":"2025-09-11T00:16:45.469073Z","end":"2025-09-11T00:16:45.733669Z","steps":["trace[1775650440] 'process raft request' (duration: 264.522668ms)"],"step_count":1} Sep 11 00:16:45.735219 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733681Z","caller":"traceutil/trace.go:171","msg":"trace[1456489348] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"195.701005ms","start":"2025-09-11T00:16:45.537971Z","end":"2025-09-11T00:16:45.733672Z","steps":["trace[1456489348] 'process raft request' (duration: 195.679114ms)"],"step_count":1} Sep 11 00:16:45.735219 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733719Z","caller":"traceutil/trace.go:171","msg":"trace[595030596] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"266.110017ms","start":"2025-09-11T00:16:45.467603Z","end":"2025-09-11T00:16:45.733713Z","steps":["trace[595030596] 'process raft request' (duration: 265.955878ms)"],"step_count":1} Sep 11 00:16:45.735219 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733735Z","caller":"traceutil/trace.go:171","msg":"trace[572880044] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:38; }","duration":"263.811783ms","start":"2025-09-11T00:16:45.469917Z","end":"2025-09-11T00:16:45.733728Z","steps":["trace[572880044] 'read index received' (duration: 64.366501ms)","trace[572880044] 'applied index is now lower than readState.Index' (duration: 199.444771ms)"],"step_count":2} Sep 11 00:16:45.735219 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733771Z","caller":"traceutil/trace.go:171","msg":"trace[1620432189] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"265.28582ms","start":"2025-09-11T00:16:45.468479Z","end":"2025-09-11T00:16:45.733765Z","steps":["trace[1620432189] 'process raft request' (duration: 265.100673ms)"],"step_count":1} Sep 11 00:16:45.735219 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733703Z","caller":"traceutil/trace.go:171","msg":"trace[303414982] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"264.370532ms","start":"2025-09-11T00:16:45.469326Z","end":"2025-09-11T00:16:45.733697Z","steps":["trace[303414982] 'process raft request' (duration: 264.286955ms)"],"step_count":1} Sep 11 00:16:45.735370 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733719Z","caller":"traceutil/trace.go:171","msg":"trace[987939595] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"263.501942ms","start":"2025-09-11T00:16:45.470211Z","end":"2025-09-11T00:16:45.733713Z","steps":["trace[987939595] 'process raft request' (duration: 263.417394ms)"],"step_count":1} Sep 11 00:16:45.735370 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.733878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.960051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} Sep 11 00:16:45.735370 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733902Z","caller":"traceutil/trace.go:171","msg":"trace[411275082] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:42; }","duration":"264.008582ms","start":"2025-09-11T00:16:45.469887Z","end":"2025-09-11T00:16:45.733896Z","steps":["trace[411275082] 'agreement among raft nodes before linearized reading' (duration: 263.958278ms)"],"step_count":1} Sep 11 00:16:45.735370 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.733908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.271477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} Sep 11 00:16:45.735370 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.733929Z","caller":"traceutil/trace.go:171","msg":"trace[267064022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:42; }","duration":"178.311352ms","start":"2025-09-11T00:16:45.555611Z","end":"2025-09-11T00:16:45.733923Z","steps":["trace[267064022] 'agreement among raft nodes before linearized reading' (duration: 178.280374ms)"],"step_count":1} Sep 11 00:16:45.986225 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.985482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.441427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} Sep 11 00:16:45.986225 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.985623Z","caller":"traceutil/trace.go:171","msg":"trace[769361469] linearizableReadLoop","detail":"{readStateIndex:51; appliedIndex:49; }","duration":"171.363834ms","start":"2025-09-11T00:16:45.814243Z","end":"2025-09-11T00:16:45.985607Z","steps":["trace[769361469] 'read index received' (duration: 28.694027ms)","trace[769361469] 'applied index is now lower than readState.Index' (duration: 142.668955ms)"],"step_count":2} Sep 11 00:16:45.986225 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.985663Z","caller":"traceutil/trace.go:171","msg":"trace[1974717282] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"181.893986ms","start":"2025-09-11T00:16:45.803716Z","end":"2025-09-11T00:16:45.985610Z","steps":["trace[1974717282] 'process raft request' (duration: 39.271709ms)","trace[1974717282] 'compare' (duration: 142.316402ms)"],"step_count":2} Sep 11 00:16:45.986225 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.985822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.150936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges\" limit:1 ","response":"range_response_count:0 size:4"} Sep 11 00:16:45.986549 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.985845Z","caller":"traceutil/trace.go:171","msg":"trace[1656037678] range","detail":"{range_begin:/registry/limitranges; range_end:; response_count:0; response_revision:46; }","duration":"141.20132ms","start":"2025-09-11T00:16:45.844638Z","end":"2025-09-11T00:16:45.985839Z","steps":["trace[1656037678] 'agreement among raft nodes before linearized reading' (duration: 141.171635ms)"],"step_count":1} Sep 11 00:16:45.986549 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:45.985747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.485402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} Sep 11 00:16:45.986549 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.985898Z","caller":"traceutil/trace.go:171","msg":"trace[1888932382] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:46; }","duration":"171.67107ms","start":"2025-09-11T00:16:45.814219Z","end":"2025-09-11T00:16:45.985891Z","steps":["trace[1888932382] 'agreement among raft nodes before linearized reading' (duration: 171.488668ms)"],"step_count":1} Sep 11 00:16:45.986549 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:45.985786Z","caller":"traceutil/trace.go:171","msg":"trace[308565180] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"179.966568ms","start":"2025-09-11T00:16:45.805811Z","end":"2025-09-11T00:16:45.985778Z","steps":["trace[308565180] 'process raft request' (duration: 179.744961ms)"],"step_count":1} Sep 11 00:16:46.252510 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:46.251952Z","caller":"traceutil/trace.go:171","msg":"trace[178476347] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"168.158482ms","start":"2025-09-11T00:16:46.083771Z","end":"2025-09-11T00:16:46.251929Z","steps":["trace[178476347] 'process raft request' (duration: 79.619587ms)","trace[178476347] 'compare' (duration: 88.368655ms)"],"step_count":2} Sep 11 00:16:46.559879 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:46.559327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.294761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:0 size:4"} Sep 11 00:16:46.559879 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:46.559406Z","caller":"traceutil/trace.go:171","msg":"trace[574593261] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:0; response_revision:86; }","duration":"207.42754ms","start":"2025-09-11T00:16:46.351963Z","end":"2025-09-11T00:16:46.559391Z","steps":["trace[574593261] 'range keys from in-memory index tree' (duration: 207.161321ms)"],"step_count":1} Sep 11 00:16:47.100707 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:47.100280Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.993139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:16:47.100707 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:47.100377Z","caller":"traceutil/trace.go:171","msg":"trace[1627132265] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"178.503155ms","start":"2025-09-11T00:16:46.921860Z","end":"2025-09-11T00:16:47.100363Z","steps":["trace[1627132265] 'process raft request' (duration: 35.375913ms)","trace[1627132265] 'compare' (duration: 142.874106ms)"],"step_count":2} Sep 11 00:16:47.469797 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:16:47.469292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.690598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:16:47.469797 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:16:47.469376Z","caller":"traceutil/trace.go:171","msg":"trace[1195916807] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"206.869003ms","start":"2025-09-11T00:16:47.262493Z","end":"2025-09-11T00:16:47.469362Z","steps":["trace[1195916807] 'process raft request' (duration: 84.059211ms)","trace[1195916807] 'compare' (duration: 122.588427ms)"],"step_count":2} Sep 11 00:17:12.805159 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:12.802535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.360783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 11 00:17:12.805159 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:12.802830Z","caller":"traceutil/trace.go:171","msg":"trace[1165104352] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"296.983251ms","start":"2025-09-11T00:17:12.505823Z","end":"2025-09-11T00:17:12.802806Z","steps":["trace[1165104352] 'process raft request' (duration: 135.264705ms)","trace[1165104352] 'compare' (duration: 161.236164ms)"],"step_count":2} Sep 11 00:17:12.809131 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:12.808609Z","caller":"traceutil/trace.go:171","msg":"trace[1171068542] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"166.774481ms","start":"2025-09-11T00:17:12.641797Z","end":"2025-09-11T00:17:12.808572Z","steps":["trace[1171068542] 'process raft request' (duration: 166.51274ms)"],"step_count":1} Sep 11 00:17:19.279260 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:19.278819Z","caller":"traceutil/trace.go:171","msg":"trace[1933824178] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"148.30648ms","start":"2025-09-11T00:17:19.130495Z","end":"2025-09-11T00:17:19.278801Z","steps":["trace[1933824178] 'process raft request' (duration: 138.653183ms)"],"step_count":1} Sep 11 00:17:21.143299 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:21.142822Z","caller":"traceutil/trace.go:171","msg":"trace[2037211830] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"142.629923ms","start":"2025-09-11T00:17:21.000165Z","end":"2025-09-11T00:17:21.142795Z","steps":["trace[2037211830] 'process raft request' (duration: 142.487913ms)"],"step_count":1} Sep 11 00:17:25.009191 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:25.008657Z","caller":"traceutil/trace.go:171","msg":"trace[1097906950] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"105.744176ms","start":"2025-09-11T00:17:24.902895Z","end":"2025-09-11T00:17:25.008639Z","steps":["trace[1097906950] 'process raft request' (duration: 103.217971ms)"],"step_count":1} Sep 11 00:17:25.011934 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:25.011532Z","caller":"traceutil/trace.go:171","msg":"trace[1746519313] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"101.962597ms","start":"2025-09-11T00:17:24.909526Z","end":"2025-09-11T00:17:25.011488Z","steps":["trace[1746519313] 'process raft request' (duration: 101.893145ms)"],"step_count":1} Sep 11 00:17:30.818864 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:30.818423Z","caller":"traceutil/trace.go:171","msg":"trace[937400361] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"145.331729ms","start":"2025-09-11T00:17:30.673075Z","end":"2025-09-11T00:17:30.818406Z","steps":["trace[937400361] 'process raft request' (duration: 145.221429ms)"],"step_count":1} Sep 11 00:17:37.899664 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:37.899250Z","caller":"traceutil/trace.go:171","msg":"trace[350811382] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"106.960044ms","start":"2025-09-11T00:17:37.792259Z","end":"2025-09-11T00:17:37.899219Z","steps":["trace[350811382] 'process raft request' (duration: 40.091601ms)","trace[350811382] 'compare' (duration: 66.741703ms)"],"step_count":2} Sep 11 00:17:42.434944 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:42.434467Z","caller":"traceutil/trace.go:171","msg":"trace[900373704] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"134.703313ms","start":"2025-09-11T00:17:42.299740Z","end":"2025-09-11T00:17:42.434443Z","steps":["trace[900373704] 'process raft request' (duration: 134.393776ms)"],"step_count":1} Sep 11 00:17:42.442309 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:42.441930Z","caller":"traceutil/trace.go:171","msg":"trace[35435324] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"132.772647ms","start":"2025-09-11T00:17:42.309140Z","end":"2025-09-11T00:17:42.441913Z","steps":["trace[35435324] 'process raft request' (duration: 132.674361ms)"],"step_count":1} Sep 11 00:17:57.436826 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:57.436388Z","caller":"traceutil/trace.go:171","msg":"trace[690511781] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"124.069364ms","start":"2025-09-11T00:17:57.312298Z","end":"2025-09-11T00:17:57.436367Z","steps":["trace[690511781] 'process raft request' (duration: 84.006842ms)","trace[690511781] 'compare' (duration: 39.980527ms)"],"step_count":2} Sep 11 00:17:57.623326 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:57.622743Z","caller":"traceutil/trace.go:171","msg":"trace[525377332] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"165.788922ms","start":"2025-09-11T00:17:57.456933Z","end":"2025-09-11T00:17:57.622722Z","steps":["trace[525377332] 'process raft request' (duration: 135.406662ms)","trace[525377332] 'compare' (duration: 30.289986ms)"],"step_count":2} Sep 11 00:18:01.395398 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:01.394911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.910872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:18:01.395398 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:01.395004Z","caller":"traceutil/trace.go:171","msg":"trace[1377768248] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"167.119278ms","start":"2025-09-11T00:18:01.227871Z","end":"2025-09-11T00:18:01.394990Z","steps":["trace[1377768248] 'process raft request' (duration: 38.065697ms)","trace[1377768248] 'compare' (duration: 128.779705ms)"],"step_count":2} Sep 11 00:18:03.484860 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:03.483380Z","caller":"traceutil/trace.go:171","msg":"trace[550150638] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"121.914603ms","start":"2025-09-11T00:18:03.361441Z","end":"2025-09-11T00:18:03.483356Z","steps":["trace[550150638] 'process raft request' (duration: 79.720318ms)","trace[550150638] 'compare' (duration: 42.069119ms)"],"step_count":2} Sep 11 00:18:13.779678 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:13.779243Z","caller":"traceutil/trace.go:171","msg":"trace[1304025492] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"103.159764ms","start":"2025-09-11T00:18:13.676064Z","end":"2025-09-11T00:18:13.779224Z","steps":["trace[1304025492] 'process raft request' (duration: 103.041201ms)"],"step_count":1} Sep 11 00:18:18.983680 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:18.983020Z","caller":"traceutil/trace.go:171","msg":"trace[2111491053] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"120.728284ms","start":"2025-09-11T00:18:18.862272Z","end":"2025-09-11T00:18:18.983000Z","steps":["trace[2111491053] 'read index received' (duration: 120.48776ms)","trace[2111491053] 'applied index is now lower than readState.Index' (duration: 239.803µs)"],"step_count":2} Sep 11 00:18:18.983680 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:18.983097Z","caller":"traceutil/trace.go:171","msg":"trace[5005033] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"124.979688ms","start":"2025-09-11T00:18:18.858091Z","end":"2025-09-11T00:18:18.983070Z","steps":["trace[5005033] 'process raft request' (duration: 124.780994ms)"],"step_count":1} Sep 11 00:18:18.983680 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:18.983224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.897693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} Sep 11 00:18:18.983680 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:18.983345Z","caller":"traceutil/trace.go:171","msg":"trace[88575107] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:861; }","duration":"121.077101ms","start":"2025-09-11T00:18:18.862237Z","end":"2025-09-11T00:18:18.983314Z","steps":["trace[88575107] 'agreement among raft nodes before linearized reading' (duration: 120.874409ms)"],"step_count":1} Sep 11 00:18:23.293451 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:23.292675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.382682ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Sep 11 00:18:23.293451 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:23.292786Z","caller":"traceutil/trace.go:171","msg":"trace[1336547562] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"207.546552ms","start":"2025-09-11T00:18:23.085225Z","end":"2025-09-11T00:18:23.292771Z","steps":["trace[1336547562] 'read index received' (duration: 21.988771ms)","trace[1336547562] 'applied index is now lower than readState.Index' (duration: 185.556379ms)"],"step_count":2} Sep 11 00:18:23.293451 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:23.292897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.308936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-controller-manager-localhost.1864123da4487241\" ","response":"range_response_count:1 size:844"} Sep 11 00:18:23.293451 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:23.292883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.633376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 11 00:18:23.293451 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:23.292930Z","caller":"traceutil/trace.go:171","msg":"trace[975265410] range","detail":"{range_begin:/registry/events/kube-system/kube-controller-manager-localhost.1864123da4487241; range_end:; response_count:1; response_revision:871; }","duration":"164.379931ms","start":"2025-09-11T00:18:23.128542Z","end":"2025-09-11T00:18:23.292922Z","steps":["trace[975265410] 'agreement among raft nodes before linearized reading' (duration: 164.305089ms)"],"step_count":1} Sep 11 00:18:23.294229 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:23.292928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.939968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} Sep 11 00:18:23.294229 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:23.292948Z","caller":"traceutil/trace.go:171","msg":"trace[1179659765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"207.743153ms","start":"2025-09-11T00:18:23.085189Z","end":"2025-09-11T00:18:23.292932Z","steps":["trace[1179659765] 'agreement among raft nodes before linearized reading' (duration: 207.633767ms)"],"step_count":1} Sep 11 00:18:23.294229 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:23.292955Z","caller":"traceutil/trace.go:171","msg":"trace[1503807921] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:871; }","duration":"107.003609ms","start":"2025-09-11T00:18:23.185944Z","end":"2025-09-11T00:18:23.292948Z","steps":["trace[1503807921] 'agreement among raft nodes before linearized reading' (duration: 106.937103ms)"],"step_count":1} Sep 11 00:18:24.392059 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:24.391556Z","caller":"traceutil/trace.go:171","msg":"trace[1607851112] transaction","detail":"{read_only:false; number_of_response:1; response_revision:878; }","duration":"154.248822ms","start":"2025-09-11T00:18:24.237286Z","end":"2025-09-11T00:18:24.391535Z","steps":["trace[1607851112] 'process raft request' (duration: 55.605547ms)","trace[1607851112] 'compare' (duration: 98.519692ms)"],"step_count":2} Sep 11 00:18:24.717399 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:24.716734Z","caller":"traceutil/trace.go:171","msg":"trace[1478264779] transaction","detail":"{read_only:false; number_of_response:1; response_revision:883; }","duration":"110.539199ms","start":"2025-09-11T00:18:24.606171Z","end":"2025-09-11T00:18:24.716710Z","steps":["trace[1478264779] 'process raft request' (duration: 64.561727ms)","trace[1478264779] 'compare' (duration: 45.807052ms)"],"step_count":2} Sep 11 00:18:24.881102 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:24.880616Z","caller":"traceutil/trace.go:171","msg":"trace[1020332640] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"151.198935ms","start":"2025-09-11T00:18:24.729398Z","end":"2025-09-11T00:18:24.880597Z","steps":["trace[1020332640] 'process raft request' (duration: 151.145224ms)"],"step_count":1} Sep 11 00:18:24.881102 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:24.880619Z","caller":"traceutil/trace.go:171","msg":"trace[1349367179] transaction","detail":"{read_only:false; number_of_response:1; response_revision:885; }","duration":"151.624147ms","start":"2025-09-11T00:18:24.728984Z","end":"2025-09-11T00:18:24.880608Z","steps":["trace[1349367179] 'process raft request' (duration: 135.728443ms)"],"step_count":1} Sep 11 00:18:24.881102 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:24.880808Z","caller":"traceutil/trace.go:171","msg":"trace[678321991] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:919; }","duration":"138.655346ms","start":"2025-09-11T00:18:24.742141Z","end":"2025-09-11T00:18:24.880797Z","steps":["trace[678321991] 'read index received' (duration: 122.61007ms)","trace[678321991] 'applied index is now lower than readState.Index' (duration: 16.044184ms)"],"step_count":2} Sep 11 00:18:24.881453 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:24.880817Z","caller":"traceutil/trace.go:171","msg":"trace[1136541186] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"151.295606ms","start":"2025-09-11T00:18:24.729512Z","end":"2025-09-11T00:18:24.880807Z","steps":["trace[1136541186] 'process raft request' (duration: 151.062177ms)"],"step_count":1} Sep 11 00:18:24.881453 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:24.880926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.76333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clustermesh-apiserver\" ","response":"range_response_count:0 size:5"} Sep 11 00:18:24.881453 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:24.881113Z","caller":"traceutil/trace.go:171","msg":"trace[1875934203] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clustermesh-apiserver; range_end:; response_count:0; response_revision:887; }","duration":"138.979296ms","start":"2025-09-11T00:18:24.742124Z","end":"2025-09-11T00:18:24.881103Z","steps":["trace[1875934203] 'agreement among raft nodes before linearized reading' (duration: 138.714167ms)"],"step_count":1} Sep 11 00:18:25.152292 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:25.151679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.975983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/clustermesh-apiserver-server-cert\" ","response":"range_response_count:0 size:5"} Sep 11 00:18:25.152292 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:25.151763Z","caller":"traceutil/trace.go:171","msg":"trace[2013292181] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"113.523162ms","start":"2025-09-11T00:18:25.038232Z","end":"2025-09-11T00:18:25.151756Z","steps":["trace[2013292181] 'process raft request' (duration: 113.422813ms)"],"step_count":1} Sep 11 00:18:25.152292 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:25.151792Z","caller":"traceutil/trace.go:171","msg":"trace[1133782088] range","detail":"{range_begin:/registry/secrets/kube-system/clustermesh-apiserver-server-cert; range_end:; response_count:0; response_revision:888; }","duration":"161.199806ms","start":"2025-09-11T00:18:24.990575Z","end":"2025-09-11T00:18:25.151775Z","steps":["trace[1133782088] 'range keys from in-memory index tree' (duration: 160.818486ms)"],"step_count":1} Sep 11 00:18:25.152292 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:25.151721Z","caller":"traceutil/trace.go:171","msg":"trace[1854144172] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"159.556408ms","start":"2025-09-11T00:18:24.992129Z","end":"2025-09-11T00:18:25.151685Z","steps":["trace[1854144172] 'process raft request' (duration: 77.955269ms)","trace[1854144172] 'compare' (duration: 81.429826ms)"],"step_count":2} Sep 11 00:18:25.948320 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:25.947847Z","caller":"traceutil/trace.go:171","msg":"trace[1323574560] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"114.17403ms","start":"2025-09-11T00:18:25.833651Z","end":"2025-09-11T00:18:25.947825Z","steps":["trace[1323574560] 'process raft request' (duration: 86.176121ms)","trace[1323574560] 'compare' (duration: 27.900365ms)"],"step_count":2} Sep 11 00:18:28.580473 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:28.579816Z","caller":"traceutil/trace.go:171","msg":"trace[1318203527] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"101.43788ms","start":"2025-09-11T00:18:28.478354Z","end":"2025-09-11T00:18:28.579792Z","steps":["trace[1318203527] 'process raft request' (duration: 95.543966ms)"],"step_count":1} Sep 11 00:18:32.345856 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.345337Z","caller":"traceutil/trace.go:171","msg":"trace[1473212122] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1047; }","duration":"263.298894ms","start":"2025-09-11T00:18:32.082019Z","end":"2025-09-11T00:18:32.345318Z","steps":["trace[1473212122] 'read index received' (duration: 25.446115ms)","trace[1473212122] 'applied index is now lower than readState.Index' (duration: 237.852118ms)"],"step_count":2} Sep 11 00:18:32.345856 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.345424Z","caller":"traceutil/trace.go:171","msg":"trace[468031400] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"282.913272ms","start":"2025-09-11T00:18:32.062475Z","end":"2025-09-11T00:18:32.345388Z","steps":["trace[468031400] 'process raft request' (duration: 282.734045ms)"],"step_count":1} Sep 11 00:18:32.345856 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.345467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.431404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 11 00:18:32.345856 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.345495Z","caller":"traceutil/trace.go:171","msg":"trace[598869676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"263.501555ms","start":"2025-09-11T00:18:32.081986Z","end":"2025-09-11T00:18:32.345487Z","steps":["trace[598869676] 'agreement among raft nodes before linearized reading' (duration: 263.44568ms)"],"step_count":1} Sep 11 00:18:32.513289 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.512620Z","caller":"traceutil/trace.go:171","msg":"trace[2147093290] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"384.109532ms","start":"2025-09-11T00:18:32.128499Z","end":"2025-09-11T00:18:32.512608Z","steps":["trace[2147093290] 'process raft request' (duration: 384.038648ms)"],"step_count":1} Sep 11 00:18:32.513289 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.512697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.681007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} Sep 11 00:18:32.513289 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.512681Z","caller":"traceutil/trace.go:171","msg":"trace[215421502] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"398.486284ms","start":"2025-09-11T00:18:32.114166Z","end":"2025-09-11T00:18:32.512653Z","steps":["trace[215421502] 'process raft request' (duration: 314.195547ms)","trace[215421502] 'compare' (duration: 84.039704ms)"],"step_count":2} Sep 11 00:18:32.513289 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.512727Z","caller":"traceutil/trace.go:171","msg":"trace[1217607437] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:1014; }","duration":"376.743257ms","start":"2025-09-11T00:18:32.135975Z","end":"2025-09-11T00:18:32.512719Z","steps":["trace[1217607437] 'agreement among raft nodes before linearized reading' (duration: 376.68177ms)"],"step_count":1} Sep 11 00:18:32.513289 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.512752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:18:32.135964Z","time spent":"376.780797ms","remote":"10.0.0.58:34684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} Sep 11 00:18:32.513564 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.512750Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.769911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} Sep 11 00:18:32.513564 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.512782Z","caller":"traceutil/trace.go:171","msg":"trace[183523665] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:1014; }","duration":"268.846053ms","start":"2025-09-11T00:18:32.243929Z","end":"2025-09-11T00:18:32.512775Z","steps":["trace[183523665] 'agreement among raft nodes before linearized reading' (duration: 268.685932ms)"],"step_count":1} Sep 11 00:18:32.513564 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.512720Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:18:32.128477Z","time spent":"384.200664ms","remote":"10.0.0.58:34638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 11 00:18:32.513564 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.512612Z","caller":"traceutil/trace.go:171","msg":"trace[1983261106] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"167.18702ms","start":"2025-09-11T00:18:32.345407Z","end":"2025-09-11T00:18:32.512594Z","steps":["trace[1983261106] 'read index received' (duration: 82.991734ms)","trace[1983261106] 'applied index is now lower than readState.Index' (duration: 84.194464ms)"],"step_count":2} Sep 11 00:18:32.513748 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.512805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:18:32.114143Z","time spent":"398.599178ms","remote":"10.0.0.58:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:18:32.723874 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.723372Z","caller":"traceutil/trace.go:171","msg":"trace[96011540] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"208.919194ms","start":"2025-09-11T00:18:32.514438Z","end":"2025-09-11T00:18:32.723358Z","steps":["trace[96011540] 'process raft request' (duration: 198.309608ms)","trace[96011540] 'compare' (duration: 10.511201ms)"],"step_count":2} Sep 11 00:18:32.723874 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.723359Z","caller":"traceutil/trace.go:171","msg":"trace[1729324098] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"206.906022ms","start":"2025-09-11T00:18:32.516434Z","end":"2025-09-11T00:18:32.723340Z","steps":["trace[1729324098] 'read index received' (duration: 196.255309ms)","trace[1729324098] 'applied index is now lower than readState.Index' (duration: 10.650082ms)"],"step_count":2} Sep 11 00:18:32.723874 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.723508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.057607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-zpfbp.18641254302f6121\" ","response":"range_response_count:1 size:788"} Sep 11 00:18:32.723874 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.723545Z","caller":"traceutil/trace.go:171","msg":"trace[1072587469] range","detail":"{range_begin:/registry/events/kube-system/cilium-zpfbp.18641254302f6121; range_end:; response_count:1; response_revision:1015; }","duration":"207.118603ms","start":"2025-09-11T00:18:32.516419Z","end":"2025-09-11T00:18:32.723537Z","steps":["trace[1072587469] 'agreement among raft nodes before linearized reading' (duration: 206.975372ms)"],"step_count":1} Sep 11 00:18:32.790132 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.789705Z","caller":"traceutil/trace.go:171","msg":"trace[1995224532] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"265.637658ms","start":"2025-09-11T00:18:32.524053Z","end":"2025-09-11T00:18:32.789690Z","steps":["trace[1995224532] 'process raft request' (duration: 265.543651ms)"],"step_count":1} Sep 11 00:18:32.790132 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:32.789825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.037047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zpfbp\" ","response":"range_response_count:1 size:18097"} Sep 11 00:18:32.790132 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.789867Z","caller":"traceutil/trace.go:171","msg":"trace[305151586] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zpfbp; range_end:; response_count:1; response_revision:1016; }","duration":"273.090046ms","start":"2025-09-11T00:18:32.516767Z","end":"2025-09-11T00:18:32.789857Z","steps":["trace[305151586] 'agreement among raft nodes before linearized reading' (duration: 272.944923ms)"],"step_count":1} Sep 11 00:18:32.954430 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.954070Z","caller":"traceutil/trace.go:171","msg":"trace[1221371395] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"152.701604ms","start":"2025-09-11T00:18:32.801349Z","end":"2025-09-11T00:18:32.954051Z","steps":["trace[1221371395] 'process raft request' (duration: 88.820827ms)","trace[1221371395] 'compare' (duration: 63.701248ms)"],"step_count":2} Sep 11 00:18:32.954609 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.954233Z","caller":"traceutil/trace.go:171","msg":"trace[2124591597] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"150.525215ms","start":"2025-09-11T00:18:32.803701Z","end":"2025-09-11T00:18:32.954226Z","steps":["trace[2124591597] 'process raft request' (duration: 150.487614ms)"],"step_count":1} Sep 11 00:18:32.954609 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:32.954296Z","caller":"traceutil/trace.go:171","msg":"trace[1929476370] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"151.795978ms","start":"2025-09-11T00:18:32.802480Z","end":"2025-09-11T00:18:32.954276Z","steps":["trace[1929476370] 'process raft request' (duration: 151.51071ms)"],"step_count":1} Sep 11 00:18:34.704357 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:34.703889Z","caller":"traceutil/trace.go:171","msg":"trace[271260327] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"186.202673ms","start":"2025-09-11T00:18:34.517668Z","end":"2025-09-11T00:18:34.703871Z","steps":["trace[271260327] 'process raft request' (duration: 186.101593ms)"],"step_count":1} Sep 11 00:18:42.231398 update_engine[1448]: I20250911 00:18:42.231257 1448 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Sep 11 00:18:42.231398 update_engine[1448]: I20250911 00:18:42.231397 1448 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Sep 11 00:18:42.232204 update_engine[1448]: I20250911 00:18:42.232177 1448 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Sep 11 00:18:42.233406 update_engine[1448]: I20250911 00:18:42.233354 1448 omaha_request_params.cc:62] Current group set to alpha Sep 11 00:18:42.233701 update_engine[1448]: I20250911 00:18:42.233652 1448 update_attempter.cc:499] Already updated boot flags. Skipping. Sep 11 00:18:42.233701 update_engine[1448]: I20250911 00:18:42.233676 1448 update_attempter.cc:643] Scheduling an action processor start. Sep 11 00:18:42.233774 update_engine[1448]: I20250911 00:18:42.233701 1448 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Sep 11 00:18:42.233824 update_engine[1448]: I20250911 00:18:42.233799 1448 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Sep 11 00:18:42.233984 update_engine[1448]: I20250911 00:18:42.233930 1448 omaha_request_action.cc:271] Posting an Omaha request to disabled Sep 11 00:18:42.233984 update_engine[1448]: I20250911 00:18:42.233963 1448 omaha_request_action.cc:272] Request: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.233984 update_engine[1448]: Sep 11 00:18:42.234298 update_engine[1448]: I20250911 00:18:42.233986 1448 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Sep 11 00:18:42.234870 locksmithd[1485]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Sep 11 00:18:42.236089 update_engine[1448]: I20250911 00:18:42.236006 1448 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Sep 11 00:18:42.236989 update_engine[1448]: I20250911 00:18:42.236933 1448 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Sep 11 00:18:42.253084 update_engine[1448]: E20250911 00:18:42.252974 1448 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Sep 11 00:18:42.253265 update_engine[1448]: I20250911 00:18:42.253181 1448 libcurl_http_fetcher.cc:283] No HTTP response, retry 1