Sep 11 00:17:14.933749 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:17:14.933785 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:17:14.933927 kernel: BIOS-provided physical RAM map: Sep 11 00:17:14.933937 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Sep 11 00:17:14.933945 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Sep 11 00:17:14.933953 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Sep 11 00:17:14.933963 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009cfdbfff] usable Sep 11 00:17:14.933971 kernel: BIOS-e820: [mem 0x000000009cfdc000-0x000000009cffffff] reserved Sep 11 00:17:14.933987 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Sep 11 00:17:14.933996 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Sep 11 00:17:14.934004 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 11 00:17:14.934012 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Sep 11 00:17:14.934020 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 11 00:17:14.934037 kernel: NX (Execute Disable) protection: active Sep 11 00:17:14.934051 kernel: APIC: Static calls initialized Sep 11 00:17:14.934060 kernel: SMBIOS 2.8 present. Sep 11 00:17:14.934072 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 Sep 11 00:17:14.934081 kernel: DMI: Memory slots populated: 1/1 Sep 11 00:17:14.934090 kernel: Hypervisor detected: KVM Sep 11 00:17:14.934099 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 11 00:17:14.934107 kernel: kvm-clock: using sched offset of 5011422737 cycles Sep 11 00:17:14.934117 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 11 00:17:14.934126 kernel: tsc: Detected 2794.750 MHz processor Sep 11 00:17:14.934138 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 11 00:17:14.934148 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 11 00:17:14.934157 kernel: last_pfn = 0x9cfdc max_arch_pfn = 0x400000000 Sep 11 00:17:14.934167 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Sep 11 00:17:14.934176 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 11 00:17:14.934185 kernel: Using GB pages for direct mapping Sep 11 00:17:14.934194 kernel: ACPI: Early table checksum verification disabled Sep 11 00:17:14.934203 kernel: ACPI: RSDP 0x00000000000F59D0 000014 (v00 BOCHS ) Sep 11 00:17:14.934212 kernel: ACPI: RSDT 0x000000009CFE241A 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:17:14.934224 kernel: ACPI: FACP 0x000000009CFE21FA 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:17:14.934233 kernel: ACPI: DSDT 0x000000009CFE0040 0021BA (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:17:14.934242 kernel: ACPI: FACS 0x000000009CFE0000 000040 Sep 11 00:17:14.934251 kernel: ACPI: APIC 0x000000009CFE22EE 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:17:14.934260 kernel: ACPI: HPET 0x000000009CFE237E 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:17:14.934269 kernel: ACPI: MCFG 0x000000009CFE23B6 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:17:14.934278 kernel: ACPI: WAET 0x000000009CFE23F2 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 11 00:17:14.934287 kernel: ACPI: Reserving FACP table memory at [mem 0x9cfe21fa-0x9cfe22ed] Sep 11 00:17:14.934304 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cfe0040-0x9cfe21f9] Sep 11 00:17:14.934313 kernel: ACPI: Reserving FACS table memory at [mem 0x9cfe0000-0x9cfe003f] Sep 11 00:17:14.934322 kernel: ACPI: Reserving APIC table memory at [mem 0x9cfe22ee-0x9cfe237d] Sep 11 00:17:14.934332 kernel: ACPI: Reserving HPET table memory at [mem 0x9cfe237e-0x9cfe23b5] Sep 11 00:17:14.934341 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cfe23b6-0x9cfe23f1] Sep 11 00:17:14.934351 kernel: ACPI: Reserving WAET table memory at [mem 0x9cfe23f2-0x9cfe2419] Sep 11 00:17:14.934363 kernel: No NUMA configuration found Sep 11 00:17:14.934372 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cfdbfff] Sep 11 00:17:14.934382 kernel: NODE_DATA(0) allocated [mem 0x9cfd4dc0-0x9cfdbfff] Sep 11 00:17:14.934391 kernel: Zone ranges: Sep 11 00:17:14.934400 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 11 00:17:14.934410 kernel: DMA32 [mem 0x0000000001000000-0x000000009cfdbfff] Sep 11 00:17:14.934419 kernel: Normal empty Sep 11 00:17:14.934428 kernel: Device empty Sep 11 00:17:14.934437 kernel: Movable zone start for each node Sep 11 00:17:14.934447 kernel: Early memory node ranges Sep 11 00:17:14.934460 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Sep 11 00:17:14.934470 kernel: node 0: [mem 0x0000000000100000-0x000000009cfdbfff] Sep 11 00:17:14.934482 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cfdbfff] Sep 11 00:17:14.934491 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 11 00:17:14.934506 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 11 00:17:14.934519 kernel: On node 0, zone DMA32: 12324 pages in unavailable ranges Sep 11 00:17:14.934528 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 11 00:17:14.934546 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 11 00:17:14.934561 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 11 00:17:14.934578 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 11 00:17:14.934587 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 11 00:17:14.934601 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 11 00:17:14.934610 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 11 00:17:14.934619 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 11 00:17:14.934628 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 11 00:17:14.934638 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 11 00:17:14.934647 kernel: TSC deadline timer available Sep 11 00:17:14.934656 kernel: CPU topo: Max. logical packages: 1 Sep 11 00:17:14.934668 kernel: CPU topo: Max. logical dies: 1 Sep 11 00:17:14.934677 kernel: CPU topo: Max. dies per package: 1 Sep 11 00:17:14.934686 kernel: CPU topo: Max. threads per core: 1 Sep 11 00:17:14.934695 kernel: CPU topo: Num. cores per package: 4 Sep 11 00:17:14.934704 kernel: CPU topo: Num. threads per package: 4 Sep 11 00:17:14.934714 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 11 00:17:14.934723 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 11 00:17:14.934732 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 11 00:17:14.934741 kernel: kvm-guest: setup PV sched yield Sep 11 00:17:14.934753 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Sep 11 00:17:14.934762 kernel: Booting paravirtualized kernel on KVM Sep 11 00:17:14.934772 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 11 00:17:14.934781 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 11 00:17:14.934791 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 11 00:17:14.934816 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 11 00:17:14.934825 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 11 00:17:14.934834 kernel: kvm-guest: PV spinlocks enabled Sep 11 00:17:14.934844 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 11 00:17:14.934858 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:17:14.934868 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 11 00:17:14.934877 kernel: random: crng init done Sep 11 00:17:14.934886 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 11 00:17:14.934895 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 11 00:17:14.934912 kernel: Fallback order for Node 0: 0 Sep 11 00:17:14.934921 kernel: Built 1 zonelists, mobility grouping on. Total pages: 642938 Sep 11 00:17:14.934931 kernel: Policy zone: DMA32 Sep 11 00:17:14.934949 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 11 00:17:14.934959 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 11 00:17:14.934969 kernel: ftrace: allocating 40106 entries in 157 pages Sep 11 00:17:14.934978 kernel: ftrace: allocated 157 pages with 5 groups Sep 11 00:17:14.934987 kernel: Dynamic Preempt: voluntary Sep 11 00:17:14.934997 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 11 00:17:14.935007 kernel: rcu: RCU event tracing is enabled. Sep 11 00:17:14.935017 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 11 00:17:14.935026 kernel: Trampoline variant of Tasks RCU enabled. Sep 11 00:17:14.935053 kernel: Rude variant of Tasks RCU enabled. Sep 11 00:17:14.935063 kernel: Tracing variant of Tasks RCU enabled. Sep 11 00:17:14.935072 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 11 00:17:14.935082 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 11 00:17:14.935091 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 11 00:17:14.935101 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:17:14.935110 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:17:14.935120 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 11 00:17:14.935129 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 11 00:17:14.935149 kernel: Console: colour VGA+ 80x25 Sep 11 00:17:14.935158 kernel: printk: legacy console [ttyS0] enabled Sep 11 00:17:14.935168 kernel: ACPI: Core revision 20240827 Sep 11 00:17:14.935180 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 11 00:17:14.935190 kernel: APIC: Switch to symmetric I/O mode setup Sep 11 00:17:14.935200 kernel: x2apic enabled Sep 11 00:17:14.935210 kernel: APIC: Switched APIC routing to: physical x2apic Sep 11 00:17:14.935222 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 11 00:17:14.935232 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 11 00:17:14.935244 kernel: kvm-guest: setup PV IPIs Sep 11 00:17:14.935254 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 11 00:17:14.935264 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 11 00:17:14.935274 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Sep 11 00:17:14.935284 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 11 00:17:14.935305 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 11 00:17:14.935325 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 11 00:17:14.935346 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 11 00:17:14.935360 kernel: Spectre V2 : Mitigation: Retpolines Sep 11 00:17:14.935371 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 11 00:17:14.935381 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 11 00:17:14.935392 kernel: active return thunk: retbleed_return_thunk Sep 11 00:17:14.935402 kernel: RETBleed: Mitigation: untrained return thunk Sep 11 00:17:14.935413 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 11 00:17:14.935424 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 11 00:17:14.935435 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 11 00:17:14.935450 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:17:14.935460 kernel: active return thunk: srso_return_thunk Sep 11 00:17:14.935471 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 11 00:17:14.935482 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 11 00:17:14.935495 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 11 00:17:14.935507 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 11 00:17:14.935519 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 11 00:17:14.935531 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 11 00:17:14.935543 kernel: Freeing SMP alternatives memory: 32K Sep 11 00:17:14.935557 kernel: pid_max: default: 32768 minimum: 301 Sep 11 00:17:14.935568 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 11 00:17:14.935579 kernel: landlock: Up and running. Sep 11 00:17:14.935589 kernel: SELinux: Initializing. Sep 11 00:17:14.935604 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 11 00:17:14.935616 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 11 00:17:14.935627 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 11 00:17:14.935637 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 11 00:17:14.935648 kernel: ... version: 0 Sep 11 00:17:14.935663 kernel: ... bit width: 48 Sep 11 00:17:14.935673 kernel: ... generic registers: 6 Sep 11 00:17:14.935684 kernel: ... value mask: 0000ffffffffffff Sep 11 00:17:14.935694 kernel: ... max period: 00007fffffffffff Sep 11 00:17:14.935705 kernel: ... fixed-purpose events: 0 Sep 11 00:17:14.935715 kernel: ... event mask: 000000000000003f Sep 11 00:17:14.935726 kernel: signal: max sigframe size: 1776 Sep 11 00:17:14.935737 kernel: rcu: Hierarchical SRCU implementation. Sep 11 00:17:14.935748 kernel: rcu: Max phase no-delay instances is 400. Sep 11 00:17:14.935763 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 11 00:17:14.935774 kernel: smp: Bringing up secondary CPUs ... Sep 11 00:17:14.935785 kernel: smpboot: x86: Booting SMP configuration: Sep 11 00:17:14.935813 kernel: .... node #0, CPUs: #1 #2 #3 Sep 11 00:17:14.935825 kernel: smp: Brought up 1 node, 4 CPUs Sep 11 00:17:14.935835 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Sep 11 00:17:14.935846 kernel: Memory: 2428916K/2571752K available (14336K kernel code, 2429K rwdata, 9960K rodata, 54036K init, 2932K bss, 136908K reserved, 0K cma-reserved) Sep 11 00:17:14.935856 kernel: devtmpfs: initialized Sep 11 00:17:14.935867 kernel: x86/mm: Memory block size: 128MB Sep 11 00:17:14.935882 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 11 00:17:14.935892 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 11 00:17:14.935903 kernel: pinctrl core: initialized pinctrl subsystem Sep 11 00:17:14.935914 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 11 00:17:14.935924 kernel: audit: initializing netlink subsys (disabled) Sep 11 00:17:14.935935 kernel: audit: type=2000 audit(1757549831.386:1): state=initialized audit_enabled=0 res=1 Sep 11 00:17:14.935945 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 11 00:17:14.935956 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 11 00:17:14.935966 kernel: cpuidle: using governor menu Sep 11 00:17:14.935981 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 11 00:17:14.935991 kernel: dca service started, version 1.12.1 Sep 11 00:17:14.936003 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff] Sep 11 00:17:14.936014 kernel: PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry Sep 11 00:17:14.936026 kernel: PCI: Using configuration type 1 for base access Sep 11 00:17:14.936047 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 11 00:17:14.936058 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 11 00:17:14.936068 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 11 00:17:14.936080 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 11 00:17:14.936095 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 11 00:17:14.936106 kernel: ACPI: Added _OSI(Module Device) Sep 11 00:17:14.936116 kernel: ACPI: Added _OSI(Processor Device) Sep 11 00:17:14.936127 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 11 00:17:14.936138 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 11 00:17:14.936148 kernel: ACPI: Interpreter enabled Sep 11 00:17:14.936158 kernel: ACPI: PM: (supports S0 S3 S5) Sep 11 00:17:14.936168 kernel: ACPI: Using IOAPIC for interrupt routing Sep 11 00:17:14.936179 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 11 00:17:14.936194 kernel: PCI: Using E820 reservations for host bridge windows Sep 11 00:17:14.936204 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 11 00:17:14.936215 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 11 00:17:14.936508 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 11 00:17:14.936857 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 11 00:17:14.937042 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 11 00:17:14.937060 kernel: PCI host bridge to bus 0000:00 Sep 11 00:17:14.937247 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 11 00:17:14.937404 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 11 00:17:14.937564 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 11 00:17:14.937713 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] Sep 11 00:17:14.937888 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Sep 11 00:17:14.938097 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window] Sep 11 00:17:14.938268 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 11 00:17:14.938509 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 11 00:17:14.938695 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 11 00:17:14.938880 kernel: pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref] Sep 11 00:17:14.939112 kernel: pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff] Sep 11 00:17:14.939275 kernel: pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref] Sep 11 00:17:14.939435 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 11 00:17:14.939635 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 11 00:17:14.939825 kernel: pci 0000:00:02.0: BAR 0 [io 0xc0c0-0xc0df] Sep 11 00:17:14.940015 kernel: pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff] Sep 11 00:17:14.940199 kernel: pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref] Sep 11 00:17:14.940434 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 11 00:17:14.940610 kernel: pci 0000:00:03.0: BAR 0 [io 0xc000-0xc07f] Sep 11 00:17:14.940784 kernel: pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff] Sep 11 00:17:14.940980 kernel: pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref] Sep 11 00:17:14.941170 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 11 00:17:14.941349 kernel: pci 0000:00:04.0: BAR 0 [io 0xc0e0-0xc0ff] Sep 11 00:17:14.941508 kernel: pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff] Sep 11 00:17:14.941668 kernel: pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref] Sep 11 00:17:14.941863 kernel: pci 0000:00:04.0: ROM [mem 0xfeb80000-0xfebbffff pref] Sep 11 00:17:14.942120 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 11 00:17:14.942296 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 11 00:17:14.942492 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 11 00:17:14.942665 kernel: pci 0000:00:1f.2: BAR 4 [io 0xc100-0xc11f] Sep 11 00:17:14.942852 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xfebd4000-0xfebd4fff] Sep 11 00:17:14.943050 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 11 00:17:14.943210 kernel: pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f] Sep 11 00:17:14.943231 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 11 00:17:14.943256 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 11 00:17:14.943268 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 11 00:17:14.943279 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 11 00:17:14.943290 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 11 00:17:14.943319 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 11 00:17:14.943331 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 11 00:17:14.943342 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 11 00:17:14.943353 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 11 00:17:14.943369 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 11 00:17:14.943380 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 11 00:17:14.943391 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 11 00:17:14.943403 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 11 00:17:14.943414 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 11 00:17:14.943429 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 11 00:17:14.943442 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 11 00:17:14.943463 kernel: iommu: Default domain type: Translated Sep 11 00:17:14.943476 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 11 00:17:14.943491 kernel: PCI: Using ACPI for IRQ routing Sep 11 00:17:14.943502 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 11 00:17:14.943519 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Sep 11 00:17:14.943530 kernel: e820: reserve RAM buffer [mem 0x9cfdc000-0x9fffffff] Sep 11 00:17:14.943721 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 11 00:17:14.943961 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 11 00:17:14.944137 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 11 00:17:14.944152 kernel: vgaarb: loaded Sep 11 00:17:14.944163 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 11 00:17:14.944179 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 11 00:17:14.944189 kernel: clocksource: Switched to clocksource kvm-clock Sep 11 00:17:14.944199 kernel: VFS: Disk quotas dquot_6.6.0 Sep 11 00:17:14.944210 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 11 00:17:14.944221 kernel: pnp: PnP ACPI init Sep 11 00:17:14.944414 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved Sep 11 00:17:14.944430 kernel: pnp: PnP ACPI: found 6 devices Sep 11 00:17:14.944440 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 11 00:17:14.944454 kernel: NET: Registered PF_INET protocol family Sep 11 00:17:14.944465 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 11 00:17:14.944475 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 11 00:17:14.944486 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 11 00:17:14.944502 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 11 00:17:14.944515 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 11 00:17:14.944526 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 11 00:17:14.944536 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 11 00:17:14.944547 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 11 00:17:14.944563 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 11 00:17:14.944575 kernel: NET: Registered PF_XDP protocol family Sep 11 00:17:14.944720 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 11 00:17:14.944879 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 11 00:17:14.945041 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 11 00:17:14.945202 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] Sep 11 00:17:14.945394 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Sep 11 00:17:14.945556 kernel: pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window] Sep 11 00:17:14.945576 kernel: PCI: CLS 0 bytes, default 64 Sep 11 00:17:14.945584 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 11 00:17:14.945593 kernel: Initialise system trusted keyrings Sep 11 00:17:14.945604 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 11 00:17:14.945615 kernel: Key type asymmetric registered Sep 11 00:17:14.945625 kernel: Asymmetric key parser 'x509' registered Sep 11 00:17:14.945636 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 11 00:17:14.945648 kernel: io scheduler mq-deadline registered Sep 11 00:17:14.945660 kernel: io scheduler kyber registered Sep 11 00:17:14.945675 kernel: io scheduler bfq registered Sep 11 00:17:14.945686 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 11 00:17:14.945699 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 11 00:17:14.945710 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 11 00:17:14.945721 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 11 00:17:14.945731 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 11 00:17:14.945743 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 11 00:17:14.945754 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 11 00:17:14.945764 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 11 00:17:14.945776 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 11 00:17:14.945953 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 11 00:17:14.945968 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 11 00:17:14.946101 kernel: rtc_cmos 00:04: registered as rtc0 Sep 11 00:17:14.946234 kernel: rtc_cmos 00:04: setting system clock to 2025-09-11T00:17:14 UTC (1757549834) Sep 11 00:17:14.946366 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs Sep 11 00:17:14.946378 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 11 00:17:14.946387 kernel: NET: Registered PF_INET6 protocol family Sep 11 00:17:14.946400 kernel: Segment Routing with IPv6 Sep 11 00:17:14.946408 kernel: In-situ OAM (IOAM) with IPv6 Sep 11 00:17:14.946417 kernel: NET: Registered PF_PACKET protocol family Sep 11 00:17:14.946425 kernel: Key type dns_resolver registered Sep 11 00:17:14.946433 kernel: IPI shorthand broadcast: enabled Sep 11 00:17:14.946441 kernel: sched_clock: Marking stable (3517004127, 121782978)->(3660343303, -21556198) Sep 11 00:17:14.946450 kernel: registered taskstats version 1 Sep 11 00:17:14.946458 kernel: Loading compiled-in X.509 certificates Sep 11 00:17:14.946466 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.46-flatcar: 941433bdd955e1c3aa4064827516bddd510466ee' Sep 11 00:17:14.946477 kernel: Demotion targets for Node 0: null Sep 11 00:17:14.946486 kernel: Key type .fscrypt registered Sep 11 00:17:14.946494 kernel: Key type fscrypt-provisioning registered Sep 11 00:17:14.946502 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 11 00:17:14.946510 kernel: ima: Allocated hash algorithm: sha1 Sep 11 00:17:14.946538 kernel: ima: No architecture policies found Sep 11 00:17:14.946547 kernel: clk: Disabling unused clocks Sep 11 00:17:14.946555 kernel: Warning: unable to open an initial console. Sep 11 00:17:14.946567 kernel: Freeing unused kernel image (initmem) memory: 54036K Sep 11 00:17:14.946575 kernel: Write protecting the kernel read-only data: 24576k Sep 11 00:17:14.946583 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 11 00:17:14.946592 kernel: Run /init as init process Sep 11 00:17:14.946600 kernel: with arguments: Sep 11 00:17:14.946608 kernel: /init Sep 11 00:17:14.946616 kernel: with environment: Sep 11 00:17:14.946624 kernel: HOME=/ Sep 11 00:17:14.946632 kernel: TERM=linux Sep 11 00:17:14.946640 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 11 00:17:14.946658 systemd[1]: Successfully made /usr/ read-only. Sep 11 00:17:14.946688 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:17:14.946700 systemd[1]: Detected virtualization kvm. Sep 11 00:17:14.946709 systemd[1]: Detected architecture x86-64. Sep 11 00:17:14.946717 systemd[1]: Running in initrd. Sep 11 00:17:14.946728 systemd[1]: No hostname configured, using default hostname. Sep 11 00:17:14.946737 systemd[1]: Hostname set to . Sep 11 00:17:14.946746 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:17:14.946755 systemd[1]: Queued start job for default target initrd.target. Sep 11 00:17:14.946764 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:17:14.946773 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:17:14.946782 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 11 00:17:14.946792 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:17:14.946828 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 11 00:17:14.946842 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 11 00:17:14.946853 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:17:14.946862 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 11 00:17:14.946871 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:17:14.946879 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:17:14.946888 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:17:14.946900 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:17:14.946909 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:17:14.946918 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:17:14.946926 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:17:14.946935 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:17:14.946944 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 11 00:17:14.946953 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 11 00:17:14.946962 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:17:14.946973 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:17:14.946982 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:17:14.946991 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:17:14.947000 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 11 00:17:14.947009 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:17:14.947020 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 11 00:17:14.947040 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:17:14.947050 systemd[1]: Starting systemd-fsck-usr.service... Sep 11 00:17:14.947059 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:17:14.947067 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:17:14.947090 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:17:14.947107 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 11 00:17:14.947120 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:17:14.947165 systemd-journald[220]: Collecting audit messages is disabled. Sep 11 00:17:14.947192 systemd[1]: Finished systemd-fsck-usr.service. Sep 11 00:17:14.947202 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:17:14.947212 systemd-journald[220]: Journal started Sep 11 00:17:14.947234 systemd-journald[220]: Runtime Journal (/run/log/journal/177708b269154007b7c60dccae6f9467) is 6M, max 48.6M, 42.5M free. Sep 11 00:17:14.953258 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:17:14.923524 systemd-modules-load[224]: Inserted module 'overlay' Sep 11 00:17:14.956963 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:17:14.964842 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:17:14.967741 systemd-modules-load[224]: Inserted module 'br_netfilter' Sep 11 00:17:15.002082 kernel: Bridge firewalling registered Sep 11 00:17:14.969493 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:17:15.003366 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:17:15.004346 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:17:15.007397 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 11 00:17:15.009115 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:17:15.011293 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:17:15.035223 systemd-tmpfiles[233]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 11 00:17:15.036584 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:17:15.038416 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:17:15.047758 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:17:15.049645 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:17:15.053143 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 11 00:17:15.056885 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:17:15.102464 dracut-cmdline[262]: 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:17:15.133541 systemd-resolved[263]: Positive Trust Anchors: Sep 11 00:17:15.133576 systemd-resolved[263]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:17:15.133616 systemd-resolved[263]: 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:17:15.137593 systemd-resolved[263]: Defaulting to hostname 'linux'. Sep 11 00:17:15.139004 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:17:15.146104 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:17:15.266861 kernel: SCSI subsystem initialized Sep 11 00:17:15.277850 kernel: Loading iSCSI transport class v2.0-870. Sep 11 00:17:15.292916 kernel: iscsi: registered transport (tcp) Sep 11 00:17:15.322149 kernel: iscsi: registered transport (qla4xxx) Sep 11 00:17:15.322246 kernel: QLogic iSCSI HBA Driver Sep 11 00:17:15.350528 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:17:15.384773 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:17:15.388391 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:17:15.461581 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 11 00:17:15.464961 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 11 00:17:15.535865 kernel: raid6: avx2x4 gen() 28582 MB/s Sep 11 00:17:15.552904 kernel: raid6: avx2x2 gen() 29696 MB/s Sep 11 00:17:15.571613 kernel: raid6: avx2x1 gen() 20208 MB/s Sep 11 00:17:15.571717 kernel: raid6: using algorithm avx2x2 gen() 29696 MB/s Sep 11 00:17:15.588049 kernel: raid6: .... xor() 18023 MB/s, rmw enabled Sep 11 00:17:15.588181 kernel: raid6: using avx2x2 recovery algorithm Sep 11 00:17:15.618858 kernel: xor: automatically using best checksumming function avx Sep 11 00:17:15.829887 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 11 00:17:15.842022 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:17:15.845395 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:17:15.884067 systemd-udevd[472]: Using default interface naming scheme 'v255'. Sep 11 00:17:15.894068 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:17:15.897545 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 11 00:17:15.931573 dracut-pre-trigger[480]: rd.md=0: removing MD RAID activation Sep 11 00:17:15.976400 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:17:15.981400 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:17:16.082681 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:17:16.085568 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 11 00:17:16.145856 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 11 00:17:16.218424 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Sep 11 00:17:16.223821 kernel: libata version 3.00 loaded. Sep 11 00:17:16.236843 kernel: cryptd: max_cpu_qlen set to 1000 Sep 11 00:17:16.236908 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Sep 11 00:17:16.241826 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:17:16.245818 kernel: AES CTR mode by8 optimization enabled Sep 11 00:17:16.245868 kernel: ahci 0000:00:1f.2: version 3.0 Sep 11 00:17:16.246133 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 11 00:17:16.249695 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 11 00:17:16.249941 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 11 00:17:16.250103 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 11 00:17:16.256307 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:17:16.256597 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:17:16.261967 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:17:16.276985 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:17:16.283959 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 11 00:17:16.300849 kernel: scsi host0: ahci Sep 11 00:17:16.304880 kernel: scsi host1: ahci Sep 11 00:17:16.305140 kernel: scsi host2: ahci Sep 11 00:17:16.306816 kernel: scsi host3: ahci Sep 11 00:17:16.325877 kernel: scsi host4: ahci Sep 11 00:17:16.329739 kernel: scsi host5: ahci Sep 11 00:17:16.330019 kernel: ata1: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4100 irq 34 lpm-pol 1 Sep 11 00:17:16.330038 kernel: ata2: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4180 irq 34 lpm-pol 1 Sep 11 00:17:16.331929 kernel: ata3: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4200 irq 34 lpm-pol 1 Sep 11 00:17:16.331954 kernel: ata4: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4280 irq 34 lpm-pol 1 Sep 11 00:17:16.331968 kernel: ata5: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4300 irq 34 lpm-pol 1 Sep 11 00:17:16.331983 kernel: ata6: SATA max UDMA/133 abar m4096@0xfebd4000 port 0xfebd4380 irq 34 lpm-pol 1 Sep 11 00:17:16.333569 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 11 00:17:16.377590 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:17:16.402281 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:17:16.404820 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 11 00:17:16.422385 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:17:16.449986 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 11 00:17:16.453637 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 11 00:17:16.494839 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:17:16.505159 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:17:16.643792 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 11 00:17:16.643921 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 11 00:17:16.643947 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 11 00:17:16.644837 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 11 00:17:16.645859 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 11 00:17:16.646835 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 11 00:17:16.647847 kernel: ata3.00: LPM support broken, forcing max_power Sep 11 00:17:16.649211 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 11 00:17:16.649243 kernel: ata3.00: applying bridge limits Sep 11 00:17:16.649259 kernel: ata3.00: LPM support broken, forcing max_power Sep 11 00:17:16.649930 kernel: ata3.00: configured for UDMA/100 Sep 11 00:17:16.652851 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 11 00:17:16.704876 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 11 00:17:16.705359 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 11 00:17:16.719899 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 11 00:17:17.009668 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 11 00:17:17.037455 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:17:17.040313 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:17:17.043038 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:17:17.046310 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 11 00:17:17.086401 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:17:17.604276 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 11 00:17:17.605339 disk-uuid[636]: The operation has completed successfully. Sep 11 00:17:17.646084 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 11 00:17:17.652891 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 11 00:17:17.705973 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 11 00:17:17.737253 sh[664]: Success Sep 11 00:17:17.760844 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 11 00:17:17.760914 kernel: device-mapper: uevent: version 1.0.3 Sep 11 00:17:17.762984 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 11 00:17:17.774849 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 11 00:17:17.820555 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 11 00:17:17.825596 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 11 00:17:17.846722 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 11 00:17:17.851144 kernel: BTRFS: device fsid 1d23f222-37c7-4ff5-813e-235ce83bed46 devid 1 transid 40 /dev/mapper/usr (253:0) scanned by mount (677) Sep 11 00:17:17.853589 kernel: BTRFS info (device dm-0): first mount of filesystem 1d23f222-37c7-4ff5-813e-235ce83bed46 Sep 11 00:17:17.853617 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:17:17.860573 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 11 00:17:17.860636 kernel: BTRFS info (device dm-0): enabling free space tree Sep 11 00:17:17.862587 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 11 00:17:17.865460 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:17:17.868503 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 11 00:17:17.871788 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 11 00:17:17.875421 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 11 00:17:17.908828 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (710) Sep 11 00:17:17.911646 kernel: BTRFS info (device vda6): first mount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:17:17.911726 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:17:17.915382 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:17:17.915432 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:17:17.920874 kernel: BTRFS info (device vda6): last unmount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:17:17.921982 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 11 00:17:17.926078 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 11 00:17:18.082459 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:17:18.085218 ignition[755]: Ignition 2.21.0 Sep 11 00:17:18.085229 ignition[755]: Stage: fetch-offline Sep 11 00:17:18.086262 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:17:18.085273 ignition[755]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:17:18.085284 ignition[755]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:17:18.085415 ignition[755]: parsed url from cmdline: "" Sep 11 00:17:18.085422 ignition[755]: no config URL provided Sep 11 00:17:18.085431 ignition[755]: reading system config file "/usr/lib/ignition/user.ign" Sep 11 00:17:18.085444 ignition[755]: no config at "/usr/lib/ignition/user.ign" Sep 11 00:17:18.085479 ignition[755]: op(1): [started] loading QEMU firmware config module Sep 11 00:17:18.085491 ignition[755]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 11 00:17:18.106351 ignition[755]: op(1): [finished] loading QEMU firmware config module Sep 11 00:17:18.106695 ignition[755]: parsing config with SHA512: 307a4ce487e40e53805e3c88b7a923f56e28eeff7936f4bb86bbeed7fae5620a13af20b88e77d44ea16f752c11d6b1a93344379a9c30cf6a7810975ef1895be7 Sep 11 00:17:18.119813 unknown[755]: fetched base config from "system" Sep 11 00:17:18.120132 unknown[755]: fetched user config from "qemu" Sep 11 00:17:18.120291 ignition[755]: fetch-offline: fetch-offline passed Sep 11 00:17:18.120389 ignition[755]: Ignition finished successfully Sep 11 00:17:18.124885 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:17:18.147918 systemd-networkd[858]: lo: Link UP Sep 11 00:17:18.147938 systemd-networkd[858]: lo: Gained carrier Sep 11 00:17:18.151063 systemd-networkd[858]: Enumeration completed Sep 11 00:17:18.151583 systemd-networkd[858]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:17:18.151589 systemd-networkd[858]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:17:18.152106 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:17:18.154641 systemd[1]: Reached target network.target - Network. Sep 11 00:17:18.155137 systemd-networkd[858]: eth0: Link UP Sep 11 00:17:18.155462 systemd-networkd[858]: eth0: Gained carrier Sep 11 00:17:18.155475 systemd-networkd[858]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:17:18.156087 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 11 00:17:18.157348 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 11 00:17:18.193901 systemd-networkd[858]: eth0: DHCPv4 address 10.0.0.70/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 11 00:17:18.296076 ignition[862]: Ignition 2.21.0 Sep 11 00:17:18.296099 ignition[862]: Stage: kargs Sep 11 00:17:18.296329 ignition[862]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:17:18.296341 ignition[862]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:17:18.300590 ignition[862]: kargs: kargs passed Sep 11 00:17:18.301398 ignition[862]: Ignition finished successfully Sep 11 00:17:18.306743 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 11 00:17:18.309658 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 11 00:17:18.359108 ignition[871]: Ignition 2.21.0 Sep 11 00:17:18.359128 ignition[871]: Stage: disks Sep 11 00:17:18.359319 ignition[871]: no configs at "/usr/lib/ignition/base.d" Sep 11 00:17:18.359331 ignition[871]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:17:18.360423 ignition[871]: disks: disks passed Sep 11 00:17:18.363703 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 11 00:17:18.360490 ignition[871]: Ignition finished successfully Sep 11 00:17:18.365887 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 11 00:17:18.368237 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 11 00:17:18.370680 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:17:18.373228 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:17:18.374506 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:17:18.378502 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 11 00:17:18.423109 systemd-fsck[881]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 11 00:17:18.563050 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 11 00:17:18.566581 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 11 00:17:18.854865 kernel: EXT4-fs (vda9): mounted filesystem 8ebc908f-0860-41e2-beed-287b778bd592 r/w with ordered data mode. Quota mode: none. Sep 11 00:17:18.855717 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 11 00:17:18.857257 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 11 00:17:18.860145 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:17:18.862252 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 11 00:17:18.863393 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 11 00:17:18.863433 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:17:18.863456 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:17:18.878465 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 11 00:17:18.880312 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 11 00:17:18.887292 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (889) Sep 11 00:17:18.887367 kernel: BTRFS info (device vda6): first mount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:17:18.887382 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:17:18.901629 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:17:18.901667 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:17:18.903764 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:17:18.967163 initrd-setup-root[913]: cut: /sysroot/etc/passwd: No such file or directory Sep 11 00:17:18.989653 initrd-setup-root[920]: cut: /sysroot/etc/group: No such file or directory Sep 11 00:17:18.994030 initrd-setup-root[927]: cut: /sysroot/etc/shadow: No such file or directory Sep 11 00:17:18.998846 initrd-setup-root[934]: cut: /sysroot/etc/gshadow: No such file or directory Sep 11 00:17:19.119947 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 11 00:17:19.123414 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 11 00:17:19.125414 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 11 00:17:19.143264 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 11 00:17:19.144469 kernel: BTRFS info (device vda6): last unmount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:17:19.179885 ignition[1003]: INFO : Ignition 2.21.0 Sep 11 00:17:19.179885 ignition[1003]: INFO : Stage: mount Sep 11 00:17:19.188265 ignition[1003]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:17:19.188265 ignition[1003]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:17:19.188265 ignition[1003]: INFO : mount: mount passed Sep 11 00:17:19.188265 ignition[1003]: INFO : Ignition finished successfully Sep 11 00:17:19.188242 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 11 00:17:19.196161 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 11 00:17:19.198544 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 11 00:17:19.266087 systemd-networkd[858]: eth0: Gained IPv6LL Sep 11 00:17:19.857495 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 11 00:17:19.962960 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1015) Sep 11 00:17:19.963047 kernel: BTRFS info (device vda6): first mount of filesystem dfd585e5-5346-4151-8d09-25f0fad7f81c Sep 11 00:17:19.963059 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 11 00:17:19.967818 kernel: BTRFS info (device vda6): turning on async discard Sep 11 00:17:19.967858 kernel: BTRFS info (device vda6): enabling free space tree Sep 11 00:17:19.970594 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 11 00:17:20.016469 ignition[1032]: INFO : Ignition 2.21.0 Sep 11 00:17:20.016469 ignition[1032]: INFO : Stage: files Sep 11 00:17:20.018613 ignition[1032]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:17:20.018613 ignition[1032]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:17:20.021816 ignition[1032]: DEBUG : files: compiled without relabeling support, skipping Sep 11 00:17:20.024282 ignition[1032]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 11 00:17:20.024282 ignition[1032]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 11 00:17:20.027769 ignition[1032]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 11 00:17:20.027769 ignition[1032]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 11 00:17:20.031340 ignition[1032]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 11 00:17:20.028280 unknown[1032]: wrote ssh authorized keys file for user: core Sep 11 00:17:20.050623 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:17:20.053202 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 11 00:17:20.053202 ignition[1032]: INFO : files: op(4): [started] processing unit "coreos-metadata.service" Sep 11 00:17:20.053202 ignition[1032]: INFO : files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 11 00:17:20.061538 ignition[1032]: INFO : files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 11 00:17:20.061538 ignition[1032]: INFO : files: op(4): [finished] processing unit "coreos-metadata.service" Sep 11 00:17:20.061538 ignition[1032]: INFO : files: op(6): [started] processing unit "etcd-member.service" Sep 11 00:17:20.067096 ignition[1032]: INFO : files: op(6): op(7): [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:17:20.067096 ignition[1032]: INFO : files: op(6): op(7): [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:17:20.067096 ignition[1032]: INFO : files: op(6): [finished] processing unit "etcd-member.service" Sep 11 00:17:20.067096 ignition[1032]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Sep 11 00:17:20.086422 ignition[1032]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 11 00:17:20.093135 ignition[1032]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 11 00:17:20.095059 ignition[1032]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Sep 11 00:17:20.095059 ignition[1032]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Sep 11 00:17:20.095059 ignition[1032]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Sep 11 00:17:20.095059 ignition[1032]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:17:20.095059 ignition[1032]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 11 00:17:20.095059 ignition[1032]: INFO : files: files passed Sep 11 00:17:20.095059 ignition[1032]: INFO : Ignition finished successfully Sep 11 00:17:20.102272 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 11 00:17:20.108156 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 11 00:17:20.111069 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 11 00:17:20.140345 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 11 00:17:20.140529 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 11 00:17:20.144032 initrd-setup-root-after-ignition[1060]: grep: /sysroot/oem/oem-release: No such file or directory Sep 11 00:17:20.145711 initrd-setup-root-after-ignition[1063]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:17:20.145711 initrd-setup-root-after-ignition[1063]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:17:20.149429 initrd-setup-root-after-ignition[1067]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 11 00:17:20.151171 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:17:20.153067 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 11 00:17:20.156945 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 11 00:17:20.234740 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 11 00:17:20.234927 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 11 00:17:20.237759 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 11 00:17:20.238449 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 11 00:17:20.240529 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 11 00:17:20.242074 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 11 00:17:20.274592 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:17:20.276821 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 11 00:17:20.301156 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:17:20.302581 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:17:20.304893 systemd[1]: Stopped target timers.target - Timer Units. Sep 11 00:17:20.306041 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 11 00:17:20.306199 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 11 00:17:20.308135 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 11 00:17:20.308481 systemd[1]: Stopped target basic.target - Basic System. Sep 11 00:17:20.308821 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 11 00:17:20.309296 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 11 00:17:20.309621 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 11 00:17:20.310104 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 11 00:17:20.310432 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 11 00:17:20.310758 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 11 00:17:20.311265 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 11 00:17:20.311579 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 11 00:17:20.311924 systemd[1]: Stopped target swap.target - Swaps. Sep 11 00:17:20.312349 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 11 00:17:20.312474 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 11 00:17:20.333186 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:17:20.333340 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:17:20.335277 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 11 00:17:20.337514 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:17:20.341215 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 11 00:17:20.341438 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 11 00:17:20.344445 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 11 00:17:20.344574 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 11 00:17:20.345720 systemd[1]: Stopped target paths.target - Path Units. Sep 11 00:17:20.347830 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 11 00:17:20.352882 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:17:20.353066 systemd[1]: Stopped target slices.target - Slice Units. Sep 11 00:17:20.355728 systemd[1]: Stopped target sockets.target - Socket Units. Sep 11 00:17:20.359093 systemd[1]: iscsid.socket: Deactivated successfully. Sep 11 00:17:20.359227 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 11 00:17:20.360163 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 11 00:17:20.360251 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 11 00:17:20.362117 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 11 00:17:20.362234 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 11 00:17:20.363614 systemd[1]: ignition-files.service: Deactivated successfully. Sep 11 00:17:20.363718 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 11 00:17:20.368778 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 11 00:17:20.371380 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 11 00:17:20.372263 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 11 00:17:20.372435 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:17:20.374041 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 11 00:17:20.374150 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 11 00:17:20.383932 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 11 00:17:20.384048 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 11 00:17:20.403533 ignition[1087]: INFO : Ignition 2.21.0 Sep 11 00:17:20.403533 ignition[1087]: INFO : Stage: umount Sep 11 00:17:20.405678 ignition[1087]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 11 00:17:20.405678 ignition[1087]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 11 00:17:20.405678 ignition[1087]: INFO : umount: umount passed Sep 11 00:17:20.405678 ignition[1087]: INFO : Ignition finished successfully Sep 11 00:17:20.407177 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 11 00:17:20.408007 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 11 00:17:20.408159 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 11 00:17:20.409450 systemd[1]: Stopped target network.target - Network. Sep 11 00:17:20.411155 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 11 00:17:20.411219 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 11 00:17:20.413155 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 11 00:17:20.413208 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 11 00:17:20.415919 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 11 00:17:20.415981 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 11 00:17:20.416080 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 11 00:17:20.416123 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 11 00:17:20.419023 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 11 00:17:20.420007 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 11 00:17:20.420517 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 11 00:17:20.420629 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 11 00:17:20.425263 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 11 00:17:20.425386 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 11 00:17:20.432080 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 11 00:17:20.432242 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 11 00:17:20.436068 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 11 00:17:20.436346 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 11 00:17:20.436473 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 11 00:17:20.438959 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 11 00:17:20.439868 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 11 00:17:20.440487 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 11 00:17:20.440640 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:17:20.444298 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 11 00:17:20.444644 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 11 00:17:20.444706 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 11 00:17:20.445420 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 11 00:17:20.445479 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:17:20.451999 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 11 00:17:20.452073 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 11 00:17:20.453042 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 11 00:17:20.453099 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:17:20.457021 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:17:20.475173 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 11 00:17:20.475327 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 11 00:17:20.480702 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 11 00:17:20.480979 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:17:20.483579 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 11 00:17:20.483642 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 11 00:17:20.485922 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 11 00:17:20.486039 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:17:20.487018 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 11 00:17:20.487084 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 11 00:17:20.487812 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 11 00:17:20.487876 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 11 00:17:20.488702 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 11 00:17:20.488764 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 11 00:17:20.497662 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 11 00:17:20.498586 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 11 00:17:20.498654 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:17:20.504265 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 11 00:17:20.504333 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:17:20.509435 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 11 00:17:20.509494 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:17:20.513695 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 11 00:17:20.513762 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:17:20.517648 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 11 00:17:20.517721 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:17:20.521156 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 11 00:17:20.521280 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 11 00:17:20.524127 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 11 00:17:20.527258 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 11 00:17:20.551970 systemd[1]: Switching root. Sep 11 00:17:20.600277 systemd-journald[220]: Journal stopped Sep 11 00:17:22.120363 systemd-journald[220]: Received SIGTERM from PID 1 (systemd). Sep 11 00:17:22.120439 kernel: SELinux: policy capability network_peer_controls=1 Sep 11 00:17:22.120454 kernel: SELinux: policy capability open_perms=1 Sep 11 00:17:22.120466 kernel: SELinux: policy capability extended_socket_class=1 Sep 11 00:17:22.120478 kernel: SELinux: policy capability always_check_network=0 Sep 11 00:17:22.120489 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 11 00:17:22.120510 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 11 00:17:22.120522 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 11 00:17:22.120533 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 11 00:17:22.120568 kernel: SELinux: policy capability userspace_initial_context=0 Sep 11 00:17:22.120600 kernel: audit: type=1403 audit(1757549840.963:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 11 00:17:22.120640 systemd[1]: Successfully loaded SELinux policy in 112.879ms. Sep 11 00:17:22.120666 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.602ms. Sep 11 00:17:22.120679 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:17:22.120692 systemd[1]: Detected virtualization kvm. Sep 11 00:17:22.120707 systemd[1]: Detected architecture x86-64. Sep 11 00:17:22.120719 systemd[1]: Detected first boot. Sep 11 00:17:22.120732 systemd[1]: Initializing machine ID from VM UUID. Sep 11 00:17:22.120762 zram_generator::config[1132]: No configuration found. Sep 11 00:17:22.120776 kernel: Guest personality initialized and is inactive Sep 11 00:17:22.120791 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 11 00:17:22.120818 kernel: Initialized host personality Sep 11 00:17:22.120831 kernel: NET: Registered PF_VSOCK protocol family Sep 11 00:17:22.120843 systemd[1]: Populated /etc with preset unit settings. Sep 11 00:17:22.120867 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 11 00:17:22.120879 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 11 00:17:22.120892 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 11 00:17:22.120905 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 11 00:17:22.120918 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 11 00:17:22.120933 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 11 00:17:22.120946 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 11 00:17:22.120958 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 11 00:17:22.120971 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 11 00:17:22.120983 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 11 00:17:22.120998 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 11 00:17:22.121014 systemd[1]: Created slice user.slice - User and Session Slice. Sep 11 00:17:22.121028 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 11 00:17:22.121041 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 11 00:17:22.121057 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 11 00:17:22.121069 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 11 00:17:22.121082 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 11 00:17:22.121094 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 11 00:17:22.121107 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 11 00:17:22.121119 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 11 00:17:22.121132 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 11 00:17:22.121146 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 11 00:17:22.121158 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 11 00:17:22.121171 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 11 00:17:22.121183 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 11 00:17:22.121196 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 11 00:17:22.121208 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 11 00:17:22.121220 systemd[1]: Reached target slices.target - Slice Units. Sep 11 00:17:22.121233 systemd[1]: Reached target swap.target - Swaps. Sep 11 00:17:22.121245 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 11 00:17:22.121260 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 11 00:17:22.121273 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 11 00:17:22.121285 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 11 00:17:22.121297 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 11 00:17:22.121311 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 11 00:17:22.121326 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 11 00:17:22.121338 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 11 00:17:22.121351 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 11 00:17:22.121363 systemd[1]: Mounting media.mount - External Media Directory... Sep 11 00:17:22.121378 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:17:22.121390 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 11 00:17:22.121402 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 11 00:17:22.121414 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 11 00:17:22.121427 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:17:22.121439 systemd[1]: Reached target machines.target - Containers. Sep 11 00:17:22.121452 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 11 00:17:22.121465 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:17:22.121477 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 11 00:17:22.121491 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 11 00:17:22.121504 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:17:22.121516 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:17:22.121528 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:17:22.121540 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 11 00:17:22.121623 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:17:22.121638 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 11 00:17:22.121650 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 11 00:17:22.121666 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 11 00:17:22.121678 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 11 00:17:22.121690 systemd[1]: Stopped systemd-fsck-usr.service. Sep 11 00:17:22.121703 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:17:22.121716 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 11 00:17:22.121728 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 11 00:17:22.121741 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 11 00:17:22.121752 kernel: loop: module loaded Sep 11 00:17:22.121764 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 11 00:17:22.121779 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 11 00:17:22.121792 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 11 00:17:22.121884 systemd[1]: verity-setup.service: Deactivated successfully. Sep 11 00:17:22.121897 systemd[1]: Stopped verity-setup.service. Sep 11 00:17:22.121916 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:17:22.121931 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 11 00:17:22.121943 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 11 00:17:22.121956 systemd[1]: Mounted media.mount - External Media Directory. Sep 11 00:17:22.121968 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 11 00:17:22.121980 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 11 00:17:22.121994 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 11 00:17:22.122009 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 11 00:17:22.122021 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 11 00:17:22.122034 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 11 00:17:22.122046 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:17:22.122058 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:17:22.122071 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:17:22.122083 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:17:22.122096 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:17:22.122110 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:17:22.122123 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 11 00:17:22.122135 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 11 00:17:22.122148 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:17:22.122159 kernel: fuse: init (API version 7.41) Sep 11 00:17:22.122172 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 11 00:17:22.122185 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 11 00:17:22.122199 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 11 00:17:22.122211 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 11 00:17:22.122226 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 11 00:17:22.122239 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 11 00:17:22.122252 kernel: ACPI: bus type drm_connector registered Sep 11 00:17:22.122264 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:17:22.122276 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:17:22.122290 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 11 00:17:22.122303 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 11 00:17:22.122339 systemd-journald[1203]: Collecting audit messages is disabled. Sep 11 00:17:22.122363 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 11 00:17:22.122375 systemd-journald[1203]: Journal started Sep 11 00:17:22.122401 systemd-journald[1203]: Runtime Journal (/run/log/journal/177708b269154007b7c60dccae6f9467) is 6M, max 48.6M, 42.5M free. Sep 11 00:17:21.627836 systemd[1]: Queued start job for default target multi-user.target. Sep 11 00:17:21.653556 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 11 00:17:21.654340 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 11 00:17:22.125267 systemd[1]: Started systemd-journald.service - Journal Service. Sep 11 00:17:22.127109 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 11 00:17:22.132053 systemd-tmpfiles[1228]: ACLs are not supported, ignoring. Sep 11 00:17:22.132076 systemd-tmpfiles[1228]: ACLs are not supported, ignoring. Sep 11 00:17:22.139055 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 11 00:17:22.152607 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 11 00:17:22.155861 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 11 00:17:22.157192 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 11 00:17:22.157228 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 11 00:17:22.159518 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 11 00:17:22.162346 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 11 00:17:22.163614 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:17:22.175057 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 11 00:17:22.179789 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 11 00:17:22.181773 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:17:22.183865 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 11 00:17:22.189411 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 11 00:17:22.197840 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 11 00:17:22.201629 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 11 00:17:22.207815 systemd-journald[1203]: Time spent on flushing to /var/log/journal/177708b269154007b7c60dccae6f9467 is 21.133ms for 961 entries. Sep 11 00:17:22.207815 systemd-journald[1203]: System Journal (/var/log/journal/177708b269154007b7c60dccae6f9467) is 8M, max 195.6M, 187.6M free. Sep 11 00:17:22.272556 systemd-journald[1203]: Received client request to flush runtime journal. Sep 11 00:17:22.273326 kernel: loop0: detected capacity change from 0 to 128016 Sep 11 00:17:22.273366 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 11 00:17:22.212443 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 11 00:17:22.238373 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 11 00:17:22.239927 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 11 00:17:22.248096 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 11 00:17:22.277303 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 11 00:17:22.286828 kernel: loop1: detected capacity change from 0 to 111000 Sep 11 00:17:22.290196 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 11 00:17:22.292632 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 11 00:17:22.297163 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 11 00:17:22.322907 kernel: loop2: detected capacity change from 0 to 128016 Sep 11 00:17:22.324282 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Sep 11 00:17:22.324309 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Sep 11 00:17:22.330166 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 11 00:17:22.336835 kernel: loop3: detected capacity change from 0 to 111000 Sep 11 00:17:22.345410 (sd-merge)[1275]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Sep 11 00:17:22.345959 (sd-merge)[1275]: Merged extensions into '/usr'. Sep 11 00:17:22.350029 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 11 00:17:22.357293 systemd[1]: Starting ensure-sysext.service... Sep 11 00:17:22.379928 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 11 00:17:22.395997 systemd[1]: Reload requested from client PID 1278 ('systemctl') (unit ensure-sysext.service)... Sep 11 00:17:22.396014 systemd[1]: Reloading... Sep 11 00:17:22.421435 systemd-tmpfiles[1279]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 11 00:17:22.421477 systemd-tmpfiles[1279]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 11 00:17:22.421881 systemd-tmpfiles[1279]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 11 00:17:22.422159 systemd-tmpfiles[1279]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 11 00:17:22.423194 systemd-tmpfiles[1279]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 11 00:17:22.423474 systemd-tmpfiles[1279]: ACLs are not supported, ignoring. Sep 11 00:17:22.423568 systemd-tmpfiles[1279]: ACLs are not supported, ignoring. Sep 11 00:17:22.433365 systemd-tmpfiles[1279]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:17:22.433378 systemd-tmpfiles[1279]: Skipping /boot Sep 11 00:17:22.552088 systemd-tmpfiles[1279]: Detected autofs mount point /boot during canonicalization of boot. Sep 11 00:17:22.552323 systemd-tmpfiles[1279]: Skipping /boot Sep 11 00:17:22.555824 zram_generator::config[1304]: No configuration found. Sep 11 00:17:22.847727 ldconfig[1254]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 11 00:17:22.872131 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 11 00:17:22.872667 systemd[1]: Reloading finished in 476 ms. Sep 11 00:17:22.898210 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 11 00:17:22.930922 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 11 00:17:22.947531 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:17:22.952475 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 11 00:17:22.960153 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 11 00:17:22.968319 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 11 00:17:22.988444 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 11 00:17:22.996285 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:17:22.996598 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:17:23.000430 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:17:23.006353 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:17:23.010762 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:17:23.015185 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:17:23.015392 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:17:23.015533 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:17:23.026341 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:17:23.027195 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:17:23.030697 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:17:23.031104 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:17:23.033510 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:17:23.033787 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:17:23.045480 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 11 00:17:23.051711 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 11 00:17:23.053786 systemd[1]: Finished ensure-sysext.service. Sep 11 00:17:23.059680 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 11 00:17:23.059892 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 11 00:17:23.061455 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 11 00:17:23.064268 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 11 00:17:23.064588 augenrules[1379]: No rules Sep 11 00:17:23.066402 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 11 00:17:23.073355 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 11 00:17:23.075016 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 11 00:17:23.075084 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:17:23.077915 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 11 00:17:23.082199 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 11 00:17:23.083523 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:17:23.084915 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:17:23.086058 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:17:23.088551 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 11 00:17:23.089266 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 11 00:17:23.091310 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 11 00:17:23.091777 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 11 00:17:23.093820 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 11 00:17:23.094126 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 11 00:17:23.098084 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 11 00:17:23.128664 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 11 00:17:23.128733 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:17:23.137090 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 11 00:17:23.137383 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 11 00:17:23.139488 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 11 00:17:23.177455 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 11 00:17:23.310769 systemd-resolved[1348]: Positive Trust Anchors: Sep 11 00:17:23.310792 systemd-resolved[1348]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 11 00:17:23.311292 systemd-resolved[1348]: 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:17:23.316669 systemd-resolved[1348]: Defaulting to hostname 'linux'. Sep 11 00:17:23.318543 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 11 00:17:23.320202 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 11 00:17:23.322251 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 11 00:17:23.324586 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 11 00:17:23.326049 systemd[1]: Reached target time-set.target - System Time Set. Sep 11 00:17:23.330035 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 11 00:17:23.332788 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 11 00:17:23.362334 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 11 00:17:23.384428 systemd-udevd[1404]: Using default interface naming scheme 'v255'. Sep 11 00:17:23.412720 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 11 00:17:23.415857 systemd[1]: Reached target sysinit.target - System Initialization. Sep 11 00:17:23.417331 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 11 00:17:23.418950 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:17:23.420837 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 11 00:17:23.422932 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 11 00:17:23.425064 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 11 00:17:23.426625 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 11 00:17:23.428299 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 11 00:17:23.428356 systemd[1]: Reached target paths.target - Path Units. Sep 11 00:17:23.429555 systemd[1]: Reached target timers.target - Timer Units. Sep 11 00:17:23.433689 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 11 00:17:23.437415 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 11 00:17:23.445961 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 11 00:17:23.448103 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 11 00:17:23.449919 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 11 00:17:23.462228 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 11 00:17:23.465985 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 11 00:17:23.473225 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 11 00:17:23.475548 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 11 00:17:23.488055 systemd[1]: Reached target sockets.target - Socket Units. Sep 11 00:17:23.489335 systemd[1]: Reached target basic.target - Basic System. Sep 11 00:17:23.490508 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:17:23.490546 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 11 00:17:23.493981 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 11 00:17:23.498135 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 11 00:17:23.502348 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 11 00:17:23.507064 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 11 00:17:23.508723 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:17:23.524362 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 11 00:17:23.527944 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 11 00:17:23.533352 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 11 00:17:23.545072 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 11 00:17:23.551095 jq[1440]: false Sep 11 00:17:23.553223 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 11 00:17:23.555950 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 11 00:17:23.558240 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 11 00:17:23.562144 systemd[1]: Starting update-engine.service - Update Engine... Sep 11 00:17:23.571117 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 11 00:17:23.589445 oslogin_cache_refresh[1442]: Refreshing passwd entry cache Sep 11 00:17:23.583824 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 11 00:17:23.591432 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Refreshing passwd entry cache Sep 11 00:17:23.586516 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 11 00:17:23.587335 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 11 00:17:23.587919 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 11 00:17:23.588209 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 11 00:17:23.600147 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 11 00:17:23.604845 extend-filesystems[1441]: Found /dev/vda6 Sep 11 00:17:23.631117 oslogin_cache_refresh[1442]: Failure getting users, quitting Sep 11 00:17:23.652873 extend-filesystems[1441]: Found /dev/vda9 Sep 11 00:17:23.652873 extend-filesystems[1441]: Checking size of /dev/vda9 Sep 11 00:17:23.655115 update_engine[1450]: I20250911 00:17:23.640928 1450 main.cc:92] Flatcar Update Engine starting Sep 11 00:17:23.637405 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 11 00:17:23.655428 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Failure getting users, quitting Sep 11 00:17:23.655428 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:17:23.655428 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Refreshing group entry cache Sep 11 00:17:23.655428 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Failure getting groups, quitting Sep 11 00:17:23.655428 google_oslogin_nss_cache[1442]: oslogin_cache_refresh[1442]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:17:23.631138 oslogin_cache_refresh[1442]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 11 00:17:23.637715 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 11 00:17:23.655615 jq[1452]: true Sep 11 00:17:23.631192 oslogin_cache_refresh[1442]: Refreshing group entry cache Sep 11 00:17:23.649309 systemd[1]: motdgen.service: Deactivated successfully. Sep 11 00:17:23.631694 oslogin_cache_refresh[1442]: Failure getting groups, quitting Sep 11 00:17:23.649597 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 11 00:17:23.631703 oslogin_cache_refresh[1442]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 11 00:17:23.658476 dbus-daemon[1437]: [system] SELinux support is enabled Sep 11 00:17:23.658949 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 11 00:17:23.663437 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:17:23.663466 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 11 00:17:23.665409 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:17:23.665428 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 11 00:17:23.672912 jq[1465]: true Sep 11 00:17:23.675387 update_engine[1450]: I20250911 00:17:23.675177 1450 update_check_scheduler.cc:74] Next update check in 8m23s Sep 11 00:17:23.678365 systemd[1]: Started update-engine.service - Update Engine. Sep 11 00:17:23.683448 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 11 00:17:23.692653 extend-filesystems[1441]: Old size kept for /dev/vda9 Sep 11 00:17:23.693742 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 11 00:17:23.694277 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 11 00:17:23.714627 systemd-networkd[1436]: lo: Link UP Sep 11 00:17:23.716854 systemd-networkd[1436]: lo: Gained carrier Sep 11 00:17:23.735439 systemd-networkd[1436]: Enumeration completed Sep 11 00:17:23.736130 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 11 00:17:23.737654 systemd[1]: Reached target network.target - Network. Sep 11 00:17:23.745369 systemd[1]: Starting containerd.service - containerd container runtime... Sep 11 00:17:23.749044 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 11 00:17:23.752858 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 11 00:17:23.758082 systemd-logind[1446]: New seat seat0. Sep 11 00:17:23.770212 systemd-networkd[1436]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:17:23.770368 systemd-networkd[1436]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 11 00:17:23.772339 systemd-networkd[1436]: eth0: Link UP Sep 11 00:17:23.774833 systemd-networkd[1436]: eth0: Gained carrier Sep 11 00:17:23.774950 systemd-networkd[1436]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 11 00:17:23.799292 systemd[1]: Started systemd-logind.service - User Login Management. Sep 11 00:17:23.805101 locksmithd[1480]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 11 00:17:23.818745 bash[1508]: Updated "/home/core/.ssh/authorized_keys" Sep 11 00:17:23.822869 kernel: mousedev: PS/2 mouse device common for all mice Sep 11 00:17:23.824404 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 11 00:17:23.828062 systemd-networkd[1436]: eth0: DHCPv4 address 10.0.0.70/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 11 00:17:23.832473 systemd-timesyncd[1388]: Network configuration changed, trying to establish connection. Sep 11 00:17:23.834427 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 11 00:17:23.839320 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 11 00:17:24.242251 systemd-resolved[1348]: Clock change detected. Flushing caches. Sep 11 00:17:24.242496 systemd-timesyncd[1388]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 11 00:17:24.242576 systemd-timesyncd[1388]: Initial clock synchronization to Thu 2025-09-11 00:17:24.242179 UTC. Sep 11 00:17:24.252368 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 11 00:17:24.257053 (ntainerd)[1514]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 11 00:17:24.257643 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 11 00:17:24.264669 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 11 00:17:24.276622 kernel: ACPI: button: Power Button [PWRF] Sep 11 00:17:24.296235 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 11 00:17:24.319468 sshd_keygen[1464]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 11 00:17:24.325208 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 11 00:17:24.329938 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 11 00:17:24.356142 systemd[1]: issuegen.service: Deactivated successfully. Sep 11 00:17:24.356446 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 11 00:17:24.360106 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 11 00:17:24.433175 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 11 00:17:24.433669 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 11 00:17:24.446193 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 11 00:17:24.454697 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 11 00:17:24.459591 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 11 00:17:24.461827 systemd[1]: Reached target getty.target - Login Prompts. Sep 11 00:17:24.555310 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 11 00:17:24.627613 systemd-logind[1446]: Watching system buttons on /dev/input/event2 (Power Button) Sep 11 00:17:24.628657 systemd-logind[1446]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 11 00:17:24.672055 kernel: kvm_amd: TSC scaling supported Sep 11 00:17:24.672132 kernel: kvm_amd: Nested Virtualization enabled Sep 11 00:17:24.672146 kernel: kvm_amd: Nested Paging enabled Sep 11 00:17:24.672158 kernel: kvm_amd: LBR virtualization supported Sep 11 00:17:24.673876 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 11 00:17:24.673907 kernel: kvm_amd: Virtual GIF supported Sep 11 00:17:24.719552 kernel: EDAC MC: Ver: 3.0.0 Sep 11 00:17:24.824662 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 11 00:17:24.866104 containerd[1514]: time="2025-09-11T00:17:24Z" 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:17:24.867290 containerd[1514]: time="2025-09-11T00:17:24.867210392Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 11 00:17:24.879898 containerd[1514]: time="2025-09-11T00:17:24.879700732Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="17.733µs" Sep 11 00:17:24.879898 containerd[1514]: time="2025-09-11T00:17:24.879755304Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 11 00:17:24.879898 containerd[1514]: time="2025-09-11T00:17:24.879780461Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 11 00:17:24.880109 containerd[1514]: time="2025-09-11T00:17:24.880079502Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 11 00:17:24.880109 containerd[1514]: time="2025-09-11T00:17:24.880100331Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 11 00:17:24.880189 containerd[1514]: time="2025-09-11T00:17:24.880127702Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 11 00:17:24.880218 containerd[1514]: time="2025-09-11T00:17:24.880200018Z" 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:17:24.880218 containerd[1514]: time="2025-09-11T00:17:24.880211950Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 11 00:17:24.880704 containerd[1514]: time="2025-09-11T00:17:24.880647767Z" 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:17:24.880704 containerd[1514]: time="2025-09-11T00:17:24.880676982Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 11 00:17:24.880704 containerd[1514]: time="2025-09-11T00:17:24.880691920Z" 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:17:24.880704 containerd[1514]: time="2025-09-11T00:17:24.880703592Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 11 00:17:24.880899 containerd[1514]: time="2025-09-11T00:17:24.880856709Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 11 00:17:24.881301 containerd[1514]: time="2025-09-11T00:17:24.881254154Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 11 00:17:24.881353 containerd[1514]: time="2025-09-11T00:17:24.881310550Z" 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:17:24.881353 containerd[1514]: time="2025-09-11T00:17:24.881326780Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 11 00:17:24.881407 containerd[1514]: time="2025-09-11T00:17:24.881374430Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 11 00:17:24.882648 containerd[1514]: time="2025-09-11T00:17:24.882126630Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 11 00:17:24.882648 containerd[1514]: time="2025-09-11T00:17:24.882367572Z" level=info msg="metadata content store policy set" policy=shared Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014086315Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014214124Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014234663Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014248759Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014264519Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014277503Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014295637Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014312358Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014327627Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014340291Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014353095Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 11 00:17:25.014380 containerd[1514]: time="2025-09-11T00:17:25.014369055Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014687692Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014722527Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014742605Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014776449Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014788631Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014801135Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014814590Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014830871Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014846770Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014862550Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 11 00:17:25.014950 containerd[1514]: time="2025-09-11T00:17:25.014874172Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 11 00:17:25.015228 containerd[1514]: time="2025-09-11T00:17:25.014984038Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 11 00:17:25.015228 containerd[1514]: time="2025-09-11T00:17:25.015005758Z" level=info msg="Start snapshots syncer" Sep 11 00:17:25.015228 containerd[1514]: time="2025-09-11T00:17:25.015040013Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 11 00:17:25.015491 containerd[1514]: time="2025-09-11T00:17:25.015412090Z" 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:17:25.015688 containerd[1514]: time="2025-09-11T00:17:25.015493232Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 11 00:17:25.017822 containerd[1514]: time="2025-09-11T00:17:25.017771174Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 11 00:17:25.018019 containerd[1514]: time="2025-09-11T00:17:25.017970417Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 11 00:17:25.018054 containerd[1514]: time="2025-09-11T00:17:25.018032854Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 11 00:17:25.018054 containerd[1514]: time="2025-09-11T00:17:25.018049475Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 11 00:17:25.018103 containerd[1514]: time="2025-09-11T00:17:25.018060696Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 11 00:17:25.018103 containerd[1514]: time="2025-09-11T00:17:25.018081636Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 11 00:17:25.018103 containerd[1514]: time="2025-09-11T00:17:25.018094279Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 11 00:17:25.018103 containerd[1514]: time="2025-09-11T00:17:25.018104789Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 11 00:17:25.018218 containerd[1514]: time="2025-09-11T00:17:25.018133603Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 11 00:17:25.018218 containerd[1514]: time="2025-09-11T00:17:25.018146848Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 11 00:17:25.018218 containerd[1514]: time="2025-09-11T00:17:25.018172496Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 11 00:17:25.018285 containerd[1514]: time="2025-09-11T00:17:25.018218683Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 11 00:17:25.018285 containerd[1514]: time="2025-09-11T00:17:25.018245944Z" 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:17:25.018285 containerd[1514]: time="2025-09-11T00:17:25.018261944Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 11 00:17:25.018285 containerd[1514]: time="2025-09-11T00:17:25.018273796Z" 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:17:25.018285 containerd[1514]: time="2025-09-11T00:17:25.018282001Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 11 00:17:25.018416 containerd[1514]: time="2025-09-11T00:17:25.018292842Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 11 00:17:25.018416 containerd[1514]: time="2025-09-11T00:17:25.018306437Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 11 00:17:25.018416 containerd[1514]: time="2025-09-11T00:17:25.018330693Z" level=info msg="runtime interface created" Sep 11 00:17:25.018416 containerd[1514]: time="2025-09-11T00:17:25.018338968Z" level=info msg="created NRI interface" Sep 11 00:17:25.018416 containerd[1514]: time="2025-09-11T00:17:25.018351542Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 11 00:17:25.018579 containerd[1514]: time="2025-09-11T00:17:25.018419279Z" level=info msg="Connect containerd service" Sep 11 00:17:25.018579 containerd[1514]: time="2025-09-11T00:17:25.018454946Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 11 00:17:25.019684 containerd[1514]: time="2025-09-11T00:17:25.019606395Z" 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:17:25.158314 containerd[1514]: time="2025-09-11T00:17:25.158147149Z" level=info msg="Start subscribing containerd event" Sep 11 00:17:25.158314 containerd[1514]: time="2025-09-11T00:17:25.158232599Z" level=info msg="Start recovering state" Sep 11 00:17:25.158536 containerd[1514]: time="2025-09-11T00:17:25.158390395Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 11 00:17:25.158536 containerd[1514]: time="2025-09-11T00:17:25.158469924Z" level=info msg="Start event monitor" Sep 11 00:17:25.158536 containerd[1514]: time="2025-09-11T00:17:25.158492997Z" level=info msg="Start cni network conf syncer for default" Sep 11 00:17:25.158622 containerd[1514]: time="2025-09-11T00:17:25.158530197Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 11 00:17:25.158801 containerd[1514]: time="2025-09-11T00:17:25.158771309Z" level=info msg="Start streaming server" Sep 11 00:17:25.158857 containerd[1514]: time="2025-09-11T00:17:25.158810733Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 11 00:17:25.158857 containerd[1514]: time="2025-09-11T00:17:25.158823737Z" level=info msg="runtime interface starting up..." Sep 11 00:17:25.158857 containerd[1514]: time="2025-09-11T00:17:25.158832423Z" level=info msg="starting plugins..." Sep 11 00:17:25.158934 containerd[1514]: time="2025-09-11T00:17:25.158857861Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 11 00:17:25.159273 systemd[1]: Started containerd.service - containerd container runtime. Sep 11 00:17:25.160874 containerd[1514]: time="2025-09-11T00:17:25.160851048Z" level=info msg="containerd successfully booted in 0.295660s" Sep 11 00:17:25.940829 systemd-networkd[1436]: eth0: Gained IPv6LL Sep 11 00:17:25.944616 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 11 00:17:25.947074 systemd[1]: Reached target network-online.target - Network is Online. Sep 11 00:17:25.950736 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 11 00:17:25.953966 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 11 00:17:26.002864 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 11 00:17:26.005247 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 11 00:17:26.005630 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 11 00:17:26.009839 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Sep 11 00:17:26.011214 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 11 00:17:26.038436 (-wrapper)[1598]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Sep 11 00:17:26.413890 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 11 00:17:26.432256 (dockerd)[1610]: 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:17:26.984391 dockerd[1610]: time="2025-09-11T00:17:26.984266622Z" level=info msg="Starting up" Sep 11 00:17:26.985373 dockerd[1610]: time="2025-09-11T00:17:26.985344283Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 11 00:17:27.054039 dockerd[1610]: time="2025-09-11T00:17:27.053969635Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 11 00:17:27.128627 dockerd[1610]: time="2025-09-11T00:17:27.128490562Z" level=info msg="Loading containers: start." Sep 11 00:17:27.143573 kernel: Initializing XFRM netlink socket Sep 11 00:17:27.586395 systemd-networkd[1436]: docker0: Link UP Sep 11 00:17:27.683242 dockerd[1610]: time="2025-09-11T00:17:27.683152854Z" level=info msg="Loading containers: done." Sep 11 00:17:27.775893 dockerd[1610]: time="2025-09-11T00:17:27.775809545Z" 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:17:27.776070 dockerd[1610]: time="2025-09-11T00:17:27.776017224Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 11 00:17:27.776165 dockerd[1610]: time="2025-09-11T00:17:27.776138662Z" level=info msg="Initializing buildkit" Sep 11 00:17:27.815651 dockerd[1610]: time="2025-09-11T00:17:27.815570086Z" level=info msg="Completed buildkit initialization" Sep 11 00:17:27.825134 dockerd[1610]: time="2025-09-11T00:17:27.825079857Z" level=info msg="Daemon has completed initialization" Sep 11 00:17:27.825331 dockerd[1610]: time="2025-09-11T00:17:27.825190624Z" level=info msg="API listen on /run/docker.sock" Sep 11 00:17:27.825559 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 11 00:17:27.829135 etcd-wrapper[1602]: Error response from daemon: No such container: etcd-member Sep 11 00:17:27.874491 etcd-wrapper[1809]: Error response from daemon: No such container: etcd-member Sep 11 00:17:27.974768 etcd-wrapper[1830]: Unable to find image 'quay.io/coreos/etcd:v3.5.16' locally Sep 11 00:17:29.519116 etcd-wrapper[1830]: v3.5.16: Pulling from coreos/etcd Sep 11 00:17:29.543917 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 11 00:17:29.545342 systemd[1]: Started sshd@0-10.0.0.70:22-10.0.0.1:54018.service - OpenSSH per-connection server daemon (10.0.0.1:54018). Sep 11 00:17:29.630797 sshd[1847]: Accepted publickey for core from 10.0.0.1 port 54018 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:29.633213 sshd-session[1847]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:29.642450 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 11 00:17:29.644134 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 11 00:17:29.652303 systemd-logind[1446]: New session 1 of user core. Sep 11 00:17:29.674569 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 11 00:17:29.678461 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 11 00:17:29.700538 (systemd)[1852]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 11 00:17:29.704067 systemd-logind[1446]: New session c1 of user core. Sep 11 00:17:29.850473 etcd-wrapper[1830]: 804c8aba2cc6: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 2ae710cd8bfe: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: d462aa345367: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 0f8b424aa0b9: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: d557676654e5: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: c8022d07192e: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: d858cbc252ad: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 1069fc2daed1: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: b40161cd83fc: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 5318d93a3a65: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 307c1adadb60: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: fbb01d9e9dc9: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: fbfea02ac3cf: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 8c26e4bf18e2: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 1e59a65f8816: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: ffbd4ca5f0bd: Pulling fs layer Sep 11 00:17:29.850473 etcd-wrapper[1830]: 0f8b424aa0b9: Waiting Sep 11 00:17:29.850473 etcd-wrapper[1830]: d557676654e5: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: c8022d07192e: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: 1e59a65f8816: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: 8c26e4bf18e2: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: d858cbc252ad: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: 307c1adadb60: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: ffbd4ca5f0bd: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: fbb01d9e9dc9: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: fbfea02ac3cf: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: 1069fc2daed1: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: 5318d93a3a65: Waiting Sep 11 00:17:29.851067 etcd-wrapper[1830]: b40161cd83fc: Waiting Sep 11 00:17:29.868465 systemd[1852]: Queued start job for default target default.target. Sep 11 00:17:29.881174 systemd[1852]: Created slice app.slice - User Application Slice. Sep 11 00:17:29.881212 systemd[1852]: Reached target paths.target - Paths. Sep 11 00:17:29.881271 systemd[1852]: Reached target timers.target - Timers. Sep 11 00:17:29.883371 systemd[1852]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 11 00:17:29.895908 systemd[1852]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 11 00:17:29.896109 systemd[1852]: Reached target sockets.target - Sockets. Sep 11 00:17:29.896173 systemd[1852]: Reached target basic.target - Basic System. Sep 11 00:17:29.896228 systemd[1852]: Reached target default.target - Main User Target. Sep 11 00:17:29.896273 systemd[1852]: Startup finished in 183ms. Sep 11 00:17:29.896407 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 11 00:17:29.906697 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 11 00:17:29.971002 systemd[1]: Started sshd@1-10.0.0.70:22-10.0.0.1:48260.service - OpenSSH per-connection server daemon (10.0.0.1:48260). Sep 11 00:17:30.034308 sshd[1863]: Accepted publickey for core from 10.0.0.1 port 48260 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:30.035913 sshd-session[1863]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:30.041449 systemd-logind[1446]: New session 2 of user core. Sep 11 00:17:30.052744 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 11 00:17:30.072349 etcd-wrapper[1830]: 2ae710cd8bfe: Verifying Checksum Sep 11 00:17:30.072349 etcd-wrapper[1830]: 2ae710cd8bfe: Download complete Sep 11 00:17:30.077675 etcd-wrapper[1830]: 804c8aba2cc6: Verifying Checksum Sep 11 00:17:30.077675 etcd-wrapper[1830]: 804c8aba2cc6: Download complete Sep 11 00:17:30.106768 sshd[1866]: Connection closed by 10.0.0.1 port 48260 Sep 11 00:17:30.107230 sshd-session[1863]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:30.119340 systemd[1]: sshd@1-10.0.0.70:22-10.0.0.1:48260.service: Deactivated successfully. Sep 11 00:17:30.121470 systemd[1]: session-2.scope: Deactivated successfully. Sep 11 00:17:30.122261 systemd-logind[1446]: Session 2 logged out. Waiting for processes to exit. Sep 11 00:17:30.125744 systemd[1]: Started sshd@2-10.0.0.70:22-10.0.0.1:48262.service - OpenSSH per-connection server daemon (10.0.0.1:48262). Sep 11 00:17:30.126389 systemd-logind[1446]: Removed session 2. Sep 11 00:17:30.189621 sshd[1877]: Accepted publickey for core from 10.0.0.1 port 48262 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:30.191197 sshd-session[1877]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:30.196477 systemd-logind[1446]: New session 3 of user core. Sep 11 00:17:30.199014 etcd-wrapper[1830]: d462aa345367: Download complete Sep 11 00:17:30.205736 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 11 00:17:30.260197 sshd[1881]: Connection closed by 10.0.0.1 port 48262 Sep 11 00:17:30.260557 sshd-session[1877]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:30.264907 systemd[1]: sshd@2-10.0.0.70:22-10.0.0.1:48262.service: Deactivated successfully. Sep 11 00:17:30.267217 systemd[1]: session-3.scope: Deactivated successfully. Sep 11 00:17:30.268041 systemd-logind[1446]: Session 3 logged out. Waiting for processes to exit. Sep 11 00:17:30.269359 systemd-logind[1446]: Removed session 3. Sep 11 00:17:30.283376 etcd-wrapper[1830]: 0f8b424aa0b9: Download complete Sep 11 00:17:30.368776 etcd-wrapper[1830]: d557676654e5: Verifying Checksum Sep 11 00:17:30.368776 etcd-wrapper[1830]: d557676654e5: Download complete Sep 11 00:17:30.398781 etcd-wrapper[1830]: 804c8aba2cc6: Pull complete Sep 11 00:17:30.567912 etcd-wrapper[1830]: c8022d07192e: Verifying Checksum Sep 11 00:17:30.567912 etcd-wrapper[1830]: c8022d07192e: Download complete Sep 11 00:17:30.590752 etcd-wrapper[1830]: d858cbc252ad: Verifying Checksum Sep 11 00:17:30.590752 etcd-wrapper[1830]: d858cbc252ad: Download complete Sep 11 00:17:30.681376 etcd-wrapper[1830]: 1069fc2daed1: Verifying Checksum Sep 11 00:17:30.681376 etcd-wrapper[1830]: 1069fc2daed1: Download complete Sep 11 00:17:30.776350 systemd[1]: var-lib-docker-overlay2-c2320d9dbc11d89e10d2da2eabb4fd410a4c88435a3959a2830a29645f4c34c6-merged.mount: Deactivated successfully. Sep 11 00:17:30.816664 etcd-wrapper[1830]: b40161cd83fc: Verifying Checksum Sep 11 00:17:30.816664 etcd-wrapper[1830]: b40161cd83fc: Download complete Sep 11 00:17:30.832571 etcd-wrapper[1830]: 5318d93a3a65: Verifying Checksum Sep 11 00:17:30.832571 etcd-wrapper[1830]: 5318d93a3a65: Download complete Sep 11 00:17:31.001648 etcd-wrapper[1830]: 307c1adadb60: Download complete Sep 11 00:17:31.028766 etcd-wrapper[1830]: 2ae710cd8bfe: Pull complete Sep 11 00:17:31.117396 etcd-wrapper[1830]: fbb01d9e9dc9: Verifying Checksum Sep 11 00:17:31.117396 etcd-wrapper[1830]: fbb01d9e9dc9: Download complete Sep 11 00:17:31.310778 etcd-wrapper[1830]: fbfea02ac3cf: Verifying Checksum Sep 11 00:17:31.312379 etcd-wrapper[1830]: fbfea02ac3cf: Download complete Sep 11 00:17:31.313258 etcd-wrapper[1830]: 8c26e4bf18e2: Verifying Checksum Sep 11 00:17:31.313258 etcd-wrapper[1830]: 8c26e4bf18e2: Download complete Sep 11 00:17:31.437710 etcd-wrapper[1830]: 1e59a65f8816: Verifying Checksum Sep 11 00:17:31.437710 etcd-wrapper[1830]: 1e59a65f8816: Download complete Sep 11 00:17:31.526850 etcd-wrapper[1830]: ffbd4ca5f0bd: Verifying Checksum Sep 11 00:17:31.623636 systemd[1]: var-lib-docker-overlay2-46aa0f3c5157178333388f6a9af794285bb771f802edf87752dfcd42d7ea5830-merged.mount: Deactivated successfully. Sep 11 00:17:31.706493 etcd-wrapper[1830]: d462aa345367: Pull complete Sep 11 00:17:31.747521 etcd-wrapper[1830]: 0f8b424aa0b9: Pull complete Sep 11 00:17:31.771209 systemd[1]: var-lib-docker-overlay2-76019a1780222314cad2d5a636bb64c2948059f7495d119161a303331ee5d33f-merged.mount: Deactivated successfully. Sep 11 00:17:31.806134 etcd-wrapper[1830]: d557676654e5: Pull complete Sep 11 00:17:31.823243 systemd[1]: var-lib-docker-overlay2-dc091afa98c8fce6c9cb812652d431e13eaad97ba92112759cb11510398ebece-merged.mount: Deactivated successfully. Sep 11 00:17:31.835426 etcd-wrapper[1830]: c8022d07192e: Pull complete Sep 11 00:17:31.852048 systemd[1]: var-lib-docker-overlay2-ef074e3c0a981d911ccfb5f845a953148441559defb660b1e1166eb059750f25-merged.mount: Deactivated successfully. Sep 11 00:17:31.864447 etcd-wrapper[1830]: d858cbc252ad: Pull complete Sep 11 00:17:31.894980 etcd-wrapper[1830]: 1069fc2daed1: Pull complete Sep 11 00:17:31.932712 etcd-wrapper[1830]: b40161cd83fc: Pull complete Sep 11 00:17:31.966848 etcd-wrapper[1830]: 5318d93a3a65: Pull complete Sep 11 00:17:32.002268 etcd-wrapper[1830]: 307c1adadb60: Pull complete Sep 11 00:17:32.237540 etcd-wrapper[1830]: fbb01d9e9dc9: Pull complete Sep 11 00:17:32.589203 etcd-wrapper[1830]: fbfea02ac3cf: Pull complete Sep 11 00:17:32.830501 etcd-wrapper[1830]: 8c26e4bf18e2: Pull complete Sep 11 00:17:32.853726 systemd[1]: var-lib-docker-overlay2-be35ded101b56cf5ef168ef8ecf35a52e228940774c8c03e45d4a78c33075fd6-merged.mount: Deactivated successfully. Sep 11 00:17:32.865846 etcd-wrapper[1830]: 1e59a65f8816: Pull complete Sep 11 00:17:32.884184 systemd[1]: var-lib-docker-overlay2-ff5dd858afde6e4ec6d1c15e27e76c5b6fea06f52187a42830d7b5d66696d171-merged.mount: Deactivated successfully. Sep 11 00:17:32.897765 etcd-wrapper[1830]: ffbd4ca5f0bd: Pull complete Sep 11 00:17:32.909476 etcd-wrapper[1830]: Digest: sha256:d967d98a12dc220a1a290794711dba7eba04b8ce465e12b02383d1bfbb33e159 Sep 11 00:17:32.914022 etcd-wrapper[1830]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.16 Sep 11 00:17:33.004803 containerd[1514]: time="2025-09-11T00:17:33.004715161Z" level=info msg="connecting to shim 130f1c7372d68099009330d579bbf0eace980ddafaa2cc7bf3a11e0c99986f40" address="unix:///run/containerd/s/f87255ed67362a8917306a67a9c2d6a07d8c2d4100bc96b349e62d34b6bd98a4" namespace=moby protocol=ttrpc version=3 Sep 11 00:17:33.037807 systemd[1]: Started docker-130f1c7372d68099009330d579bbf0eace980ddafaa2cc7bf3a11e0c99986f40.scope - libcontainer container 130f1c7372d68099009330d579bbf0eace980ddafaa2cc7bf3a11e0c99986f40. Sep 11 00:17:33.060792 systemd-resolved[1348]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 11 00:17:33.161947 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.161363Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Sep 11 00:17:33.161947 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.161497Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"177708b269154007b7c60dccae6f9467"} Sep 11 00:17:33.161947 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:33.161535Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Sep 11 00:17:33.161947 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:33.161543Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.16"} Sep 11 00:17:33.161947 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:33.161549Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Sep 11 00:17:33.162245 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:33.161771Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Sep 11 00:17:33.162245 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:33.161816Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 11 00:17:33.162245 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.161875Z","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.70:2379"]} Sep 11 00:17:33.162799 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:33.161944Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Sep 11 00:17:33.162799 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.161959Z","caller":"embed/etcd.go:128","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Sep 11 00:17:33.162998 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.162716Z","caller":"embed/etcd.go:136","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Sep 11 00:17:33.163304 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.162886Z","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":"177708b269154007b7c60dccae6f9467","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.70:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"177708b269154007b7c60dccae6f9467=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:17:33.166594 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.166316Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.026675ms"} Sep 11 00:17:33.171862 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.171587Z","caller":"etcdserver/raft.go:505","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Sep 11 00:17:33.171862 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.171668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Sep 11 00:17:33.171862 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.171712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Sep 11 00:17:33.171862 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.171720Z","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:17:33.171862 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.171734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Sep 11 00:17:33.172310 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.171765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 11 00:17:33.176116 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:17:33.175887Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Sep 11 00:17:33.177218 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.177008Z","caller":"mvcc/kvstore.go:423","msg":"kvstore restored","current-rev":1} Sep 11 00:17:33.178137 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.177949Z","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:17:33.179216 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.179019Z","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:17:33.179440 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.179243Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 11 00:17:33.179596 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.179310Z","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:17:33.179905 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.179676Z","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:17:33.179905 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.179748Z","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:17:33.179905 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.179768Z","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:17:33.181162 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.180655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Sep 11 00:17:33.181162 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.180781Z","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:17:33.181873 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.181661Z","caller":"embed/etcd.go:600","msg":"serving peer traffic","address":"127.0.0.1:2380"} Sep 11 00:17:33.181873 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.181712Z","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.70:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Sep 11 00:17:33.181961 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.181733Z","caller":"embed/etcd.go:572","msg":"cmux::serve","address":"127.0.0.1:2380"} Sep 11 00:17:33.972762 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.972264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Sep 11 00:17:33.972762 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.972337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Sep 11 00:17:33.972762 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.972375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Sep 11 00:17:33.972762 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.972397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Sep 11 00:17:33.972762 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.972403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Sep 11 00:17:33.972762 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.972411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Sep 11 00:17:33.972762 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.972419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Sep 11 00:17:33.982530 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.981830Z","caller":"etcdserver/server.go:2651","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Sep 11 00:17:33.982530 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.981876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} Sep 11 00:17:33.982530 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.981883Z","caller":"etcdserver/server.go:2140","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:177708b269154007b7c60dccae6f9467 ClientURLs:[http://10.0.0.70:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Sep 11 00:17:33.982530 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.982312Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Sep 11 00:17:33.982827 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.982558Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Sep 11 00:17:33.982921 systemd[1]: Started etcd-member.service - etcd (System Application Container). Sep 11 00:17:33.983479 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.982731Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Sep 11 00:17:33.983479 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:33.983255Z","caller":"embed/serve.go:187","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Sep 11 00:17:33.983607 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 11 00:17:33.983986 systemd[1]: Startup finished in 3.609s (kernel) + 6.247s (initrd) + 12.727s (userspace) = 22.584s. Sep 11 00:17:34.003083 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:34.002629Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Sep 11 00:17:34.003083 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:34.002745Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Sep 11 00:17:34.003083 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:17:34.002787Z","caller":"etcdserver/server.go:2675","msg":"cluster version is updated","cluster-version":"3.5"} Sep 11 00:17:40.279754 systemd[1]: Started sshd@3-10.0.0.70:22-10.0.0.1:48956.service - OpenSSH per-connection server daemon (10.0.0.1:48956). Sep 11 00:17:40.386425 sshd[2017]: Accepted publickey for core from 10.0.0.1 port 48956 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:40.388314 sshd-session[2017]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:40.393554 systemd-logind[1446]: New session 4 of user core. Sep 11 00:17:40.403834 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 11 00:17:40.461410 sshd[2020]: Connection closed by 10.0.0.1 port 48956 Sep 11 00:17:40.462093 sshd-session[2017]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:40.472210 systemd[1]: sshd@3-10.0.0.70:22-10.0.0.1:48956.service: Deactivated successfully. Sep 11 00:17:40.474809 systemd[1]: session-4.scope: Deactivated successfully. Sep 11 00:17:40.475845 systemd-logind[1446]: Session 4 logged out. Waiting for processes to exit. Sep 11 00:17:40.478192 systemd-logind[1446]: Removed session 4. Sep 11 00:17:40.479499 systemd[1]: Started sshd@4-10.0.0.70:22-10.0.0.1:48966.service - OpenSSH per-connection server daemon (10.0.0.1:48966). Sep 11 00:17:40.546106 sshd[2026]: Accepted publickey for core from 10.0.0.1 port 48966 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:40.547822 sshd-session[2026]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:40.553478 systemd-logind[1446]: New session 5 of user core. Sep 11 00:17:40.563800 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 11 00:17:40.616899 sshd[2029]: Connection closed by 10.0.0.1 port 48966 Sep 11 00:17:40.617387 sshd-session[2026]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:40.633528 systemd[1]: sshd@4-10.0.0.70:22-10.0.0.1:48966.service: Deactivated successfully. Sep 11 00:17:40.635601 systemd[1]: session-5.scope: Deactivated successfully. Sep 11 00:17:40.636441 systemd-logind[1446]: Session 5 logged out. Waiting for processes to exit. Sep 11 00:17:40.639750 systemd[1]: Started sshd@5-10.0.0.70:22-10.0.0.1:48980.service - OpenSSH per-connection server daemon (10.0.0.1:48980). Sep 11 00:17:40.640351 systemd-logind[1446]: Removed session 5. Sep 11 00:17:40.693538 sshd[2035]: Accepted publickey for core from 10.0.0.1 port 48980 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:40.695321 sshd-session[2035]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:40.700174 systemd-logind[1446]: New session 6 of user core. Sep 11 00:17:40.718703 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 11 00:17:40.774524 sshd[2038]: Connection closed by 10.0.0.1 port 48980 Sep 11 00:17:40.775097 sshd-session[2035]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:40.784870 systemd[1]: sshd@5-10.0.0.70:22-10.0.0.1:48980.service: Deactivated successfully. Sep 11 00:17:40.787027 systemd[1]: session-6.scope: Deactivated successfully. Sep 11 00:17:40.787901 systemd-logind[1446]: Session 6 logged out. Waiting for processes to exit. Sep 11 00:17:40.791630 systemd[1]: Started sshd@6-10.0.0.70:22-10.0.0.1:48986.service - OpenSSH per-connection server daemon (10.0.0.1:48986). Sep 11 00:17:40.792497 systemd-logind[1446]: Removed session 6. Sep 11 00:17:40.849454 sshd[2044]: Accepted publickey for core from 10.0.0.1 port 48986 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:40.851131 sshd-session[2044]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:40.858070 systemd-logind[1446]: New session 7 of user core. Sep 11 00:17:40.870721 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 11 00:17:40.934637 sudo[2048]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 11 00:17:40.935154 sudo[2048]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:17:40.950569 sudo[2048]: pam_unix(sudo:session): session closed for user root Sep 11 00:17:40.952732 sshd[2047]: Connection closed by 10.0.0.1 port 48986 Sep 11 00:17:40.953302 sshd-session[2044]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:40.965498 systemd[1]: sshd@6-10.0.0.70:22-10.0.0.1:48986.service: Deactivated successfully. Sep 11 00:17:40.968703 systemd[1]: session-7.scope: Deactivated successfully. Sep 11 00:17:40.969853 systemd-logind[1446]: Session 7 logged out. Waiting for processes to exit. Sep 11 00:17:40.973081 systemd[1]: Started sshd@7-10.0.0.70:22-10.0.0.1:48988.service - OpenSSH per-connection server daemon (10.0.0.1:48988). Sep 11 00:17:40.974288 systemd-logind[1446]: Removed session 7. Sep 11 00:17:41.048620 sshd[2054]: Accepted publickey for core from 10.0.0.1 port 48988 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:41.051042 sshd-session[2054]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:41.057215 systemd-logind[1446]: New session 8 of user core. Sep 11 00:17:41.068038 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 11 00:17:41.129167 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:17:41.129606 sudo[2059]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:17:41.142982 sudo[2059]: pam_unix(sudo:session): session closed for user root Sep 11 00:17:41.153717 sudo[2058]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 11 00:17:41.154388 sudo[2058]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 11 00:17:41.170204 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 11 00:17:41.236529 augenrules[2081]: No rules Sep 11 00:17:41.238614 systemd[1]: audit-rules.service: Deactivated successfully. Sep 11 00:17:41.238975 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 11 00:17:41.240305 sudo[2058]: pam_unix(sudo:session): session closed for user root Sep 11 00:17:41.242292 sshd[2057]: Connection closed by 10.0.0.1 port 48988 Sep 11 00:17:41.242666 sshd-session[2054]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:41.252760 systemd[1]: sshd@7-10.0.0.70:22-10.0.0.1:48988.service: Deactivated successfully. Sep 11 00:17:41.255316 systemd[1]: session-8.scope: Deactivated successfully. Sep 11 00:17:41.256227 systemd-logind[1446]: Session 8 logged out. Waiting for processes to exit. Sep 11 00:17:41.259797 systemd[1]: Started sshd@8-10.0.0.70:22-10.0.0.1:49004.service - OpenSSH per-connection server daemon (10.0.0.1:49004). Sep 11 00:17:41.260623 systemd-logind[1446]: Removed session 8. Sep 11 00:17:41.323379 sshd[2090]: Accepted publickey for core from 10.0.0.1 port 49004 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:41.325056 sshd-session[2090]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:41.330254 systemd-logind[1446]: New session 9 of user core. Sep 11 00:17:41.345805 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 11 00:17:41.397288 sshd[2093]: Connection closed by 10.0.0.1 port 49004 Sep 11 00:17:41.397557 sshd-session[2090]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:41.422353 systemd[1]: sshd@8-10.0.0.70:22-10.0.0.1:49004.service: Deactivated successfully. Sep 11 00:17:41.424481 systemd[1]: session-9.scope: Deactivated successfully. Sep 11 00:17:41.425357 systemd-logind[1446]: Session 9 logged out. Waiting for processes to exit. Sep 11 00:17:41.428240 systemd[1]: Started sshd@9-10.0.0.70:22-10.0.0.1:49008.service - OpenSSH per-connection server daemon (10.0.0.1:49008). Sep 11 00:17:41.429196 systemd-logind[1446]: Removed session 9. Sep 11 00:17:41.488844 sshd[2101]: Accepted publickey for core from 10.0.0.1 port 49008 ssh2: RSA SHA256:y/XwUTkYMtMNacauLj7j4r7D0OZbB+8bKKbHTNwhPa4 Sep 11 00:17:41.490231 sshd-session[2101]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 11 00:17:41.495059 systemd-logind[1446]: New session 10 of user core. Sep 11 00:17:41.505647 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 11 00:17:42.399982 sshd[2104]: Connection closed by 10.0.0.1 port 49008 Sep 11 00:17:42.400560 sshd-session[2101]: pam_unix(sshd:session): session closed for user core Sep 11 00:17:42.406851 systemd[1]: sshd@9-10.0.0.70:22-10.0.0.1:49008.service: Deactivated successfully. Sep 11 00:17:42.410253 systemd[1]: session-10.scope: Deactivated successfully. Sep 11 00:17:42.411259 systemd-logind[1446]: Session 10 logged out. Waiting for processes to exit. Sep 11 00:17:42.414118 systemd-logind[1446]: Removed session 10. Sep 11 00:18:09.426987 update_engine[1450]: I20250911 00:18:09.426815 1450 update_attempter.cc:509] Updating boot flags... Sep 11 00:18:53.138234 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:53.137325Z","caller":"traceutil/trace.go:171","msg":"trace[1620848964] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"160.220561ms","start":"2025-09-11T00:18:52.977083Z","end":"2025-09-11T00:18:53.137303Z","steps":["trace[1620848964] 'process raft request' (duration: 159.917433ms)"],"step_count":1} Sep 11 00:18:53.205415 etcd-wrapper[1830]: 2025/09/11 00:18:53 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" Sep 11 00:18:53.205415 etcd-wrapper[1830]: 2025/09/11 00:18:53 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" Sep 11 00:18:55.368270 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:55.367710Z","caller":"traceutil/trace.go:171","msg":"trace[1402627266] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"145.177879ms","start":"2025-09-11T00:18:55.222496Z","end":"2025-09-11T00:18:55.367674Z","steps":["trace[1402627266] 'read index received' (duration: 145.056921ms)","trace[1402627266] 'applied index is now lower than readState.Index' (duration: 120.126µs)"],"step_count":2} Sep 11 00:18:55.368270 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:55.367744Z","caller":"traceutil/trace.go:171","msg":"trace[1850150630] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"146.285926ms","start":"2025-09-11T00:18:55.221436Z","end":"2025-09-11T00:18:55.367722Z","steps":["trace[1850150630] 'process raft request' (duration: 146.095027ms)"],"step_count":1} Sep 11 00:18:55.368270 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:55.367988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.393743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" limit:1 ","response":"range_response_count:0 size:4"} Sep 11 00:18:55.368270 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:55.368038Z","caller":"traceutil/trace.go:171","msg":"trace[1200088390] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"145.540869ms","start":"2025-09-11T00:18:55.222483Z","end":"2025-09-11T00:18:55.368024Z","steps":["trace[1200088390] 'agreement among raft nodes before linearized reading' (duration: 145.348177ms)"],"step_count":1} Sep 11 00:18:55.623455 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:55.622851Z","caller":"traceutil/trace.go:171","msg":"trace[1617652118] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"124.852717ms","start":"2025-09-11T00:18:55.497963Z","end":"2025-09-11T00:18:55.622816Z","steps":["trace[1617652118] 'read index received' (duration: 119.210853ms)","trace[1617652118] 'applied index is now lower than readState.Index' (duration: 5.639489ms)"],"step_count":2} Sep 11 00:18:55.623455 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:55.622857Z","caller":"traceutil/trace.go:171","msg":"trace[926039435] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"129.853929ms","start":"2025-09-11T00:18:55.492969Z","end":"2025-09-11T00:18:55.622823Z","steps":["trace[926039435] 'process raft request' (duration: 124.21422ms)"],"step_count":1} Sep 11 00:18:55.623455 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:55.622962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.991938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" limit:1 ","response":"range_response_count:0 size:4"} Sep 11 00:18:55.623455 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:55.623050Z","caller":"traceutil/trace.go:171","msg":"trace[935838552] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:0; response_revision:75; }","duration":"125.148522ms","start":"2025-09-11T00:18:55.497886Z","end":"2025-09-11T00:18:55.623034Z","steps":["trace[935838552] 'agreement among raft nodes before linearized reading' (duration: 124.978302ms)"],"step_count":1} Sep 11 00:18:55.971109 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:55.970677Z","caller":"traceutil/trace.go:171","msg":"trace[889824908] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"181.499607ms","start":"2025-09-11T00:18:55.789154Z","end":"2025-09-11T00:18:55.970654Z","steps":["trace[889824908] 'process raft request' (duration: 124.405898ms)","trace[889824908] 'compare' (duration: 56.939019ms)"],"step_count":2} Sep 11 00:18:59.023341 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:59.022931Z","caller":"traceutil/trace.go:171","msg":"trace[878262642] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"151.539713ms","start":"2025-09-11T00:18:58.871367Z","end":"2025-09-11T00:18:59.022906Z","steps":["trace[878262642] 'process raft request' (duration: 79.213843ms)","trace[878262642] 'compare' (duration: 72.179506ms)"],"step_count":2} Sep 11 00:18:59.361214 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:18:59.360613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.948532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:18:59.361214 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:59.360794Z","caller":"traceutil/trace.go:171","msg":"trace[157716626] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"288.904463ms","start":"2025-09-11T00:18:59.071854Z","end":"2025-09-11T00:18:59.360759Z","steps":["trace[157716626] 'process raft request' (duration: 145.406862ms)","trace[157716626] 'compare' (duration: 142.834037ms)"],"step_count":2} Sep 11 00:18:59.361214 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:59.360851Z","caller":"traceutil/trace.go:171","msg":"trace[1343450012] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"288.387773ms","start":"2025-09-11T00:18:59.072453Z","end":"2025-09-11T00:18:59.360841Z","steps":["trace[1343450012] 'process raft request' (duration: 288.242371ms)"],"step_count":1} Sep 11 00:18:59.361214 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:18:59.360913Z","caller":"traceutil/trace.go:171","msg":"trace[2052295623] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"287.566083ms","start":"2025-09-11T00:18:59.073343Z","end":"2025-09-11T00:18:59.360910Z","steps":["trace[2052295623] 'process raft request' (duration: 287.392207ms)"],"step_count":1} Sep 11 00:19:00.852874 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:00.852257Z","caller":"traceutil/trace.go:171","msg":"trace[709424934] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"165.577761ms","start":"2025-09-11T00:19:00.686645Z","end":"2025-09-11T00:19:00.852223Z","steps":["trace[709424934] 'read index received' (duration: 98.441625ms)","trace[709424934] 'applied index is now lower than readState.Index' (duration: 67.135204ms)"],"step_count":2} Sep 11 00:19:00.852874 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:00.852315Z","caller":"traceutil/trace.go:171","msg":"trace[1868322555] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"166.714009ms","start":"2025-09-11T00:19:00.685565Z","end":"2025-09-11T00:19:00.852279Z","steps":["trace[1868322555] 'process raft request' (duration: 99.593904ms)","trace[1868322555] 'compare' (duration: 66.973379ms)"],"step_count":2} Sep 11 00:19:00.853659 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:00.852689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.021216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" limit:1 ","response":"range_response_count:0 size:5"} Sep 11 00:19:00.853659 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:00.852728Z","caller":"traceutil/trace.go:171","msg":"trace[1890218897] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:0; response_revision:295; }","duration":"166.10939ms","start":"2025-09-11T00:19:00.686611Z","end":"2025-09-11T00:19:00.852720Z","steps":["trace[1890218897] 'agreement among raft nodes before linearized reading' (duration: 165.734414ms)"],"step_count":1} Sep 11 00:19:11.579976 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:11.579585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.645258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:19:11.579976 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.579686Z","caller":"traceutil/trace.go:171","msg":"trace[1782605780] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"309.772712ms","start":"2025-09-11T00:19:11.269899Z","end":"2025-09-11T00:19:11.579672Z","steps":["trace[1782605780] 'process raft request' (duration: 67.946216ms)","trace[1782605780] 'compare' (duration: 241.450665ms)"],"step_count":2} Sep 11 00:19:11.579976 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:11.579732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:19:11.269869Z","time spent":"309.842637ms","remote":"10.0.0.74:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11499,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 11 00:19:11.594632 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.594309Z","caller":"traceutil/trace.go:171","msg":"trace[673392609] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"307.838853ms","start":"2025-09-11T00:19:11.286459Z","end":"2025-09-11T00:19:11.594298Z","steps":["trace[673392609] 'process raft request' (duration: 307.747688ms)"],"step_count":1} Sep 11 00:19:11.594867 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.594367Z","caller":"traceutil/trace.go:171","msg":"trace[104473220] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:433; }","duration":"233.421933ms","start":"2025-09-11T00:19:11.360932Z","end":"2025-09-11T00:19:11.594354Z","steps":["trace[104473220] 'read index received' (duration: 233.284139ms)","trace[104473220] 'applied index is now lower than readState.Index' (duration: 136.983µs)"],"step_count":2} Sep 11 00:19:11.594867 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.594457Z","caller":"traceutil/trace.go:171","msg":"trace[197285223] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"302.913224ms","start":"2025-09-11T00:19:11.291533Z","end":"2025-09-11T00:19:11.594447Z","steps":["trace[197285223] 'process raft request' (duration: 302.743869ms)"],"step_count":1} Sep 11 00:19:11.594867 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:11.594458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.526103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 11 00:19:11.594867 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.594487Z","caller":"traceutil/trace.go:171","msg":"trace[577086356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"233.592801ms","start":"2025-09-11T00:19:11.360888Z","end":"2025-09-11T00:19:11.594481Z","steps":["trace[577086356] 'agreement among raft nodes before linearized reading' (duration: 233.54556ms)"],"step_count":1} Sep 11 00:19:11.595091 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:11.594597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:19:11.286434Z","time spent":"307.914808ms","remote":"10.0.0.74:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3392,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:19:11.595091 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:11.594734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:19:11.291489Z","time spent":"302.993288ms","remote":"10.0.0.74:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3218,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:19:11.639269 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.638910Z","caller":"traceutil/trace.go:171","msg":"trace[1733691052] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"120.6267ms","start":"2025-09-11T00:19:11.518264Z","end":"2025-09-11T00:19:11.638890Z","steps":["trace[1733691052] 'process raft request' (duration: 120.43414ms)"],"step_count":1} Sep 11 00:19:11.751166 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.750749Z","caller":"traceutil/trace.go:171","msg":"trace[595827077] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"151.107197ms","start":"2025-09-11T00:19:11.599624Z","end":"2025-09-11T00:19:11.750731Z","steps":["trace[595827077] 'process raft request' (duration: 151.06156ms)"],"step_count":1} Sep 11 00:19:11.751166 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.750844Z","caller":"traceutil/trace.go:171","msg":"trace[18571400] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"152.168232ms","start":"2025-09-11T00:19:11.598640Z","end":"2025-09-11T00:19:11.750808Z","steps":["trace[18571400] 'process raft request' (duration: 87.29984ms)","trace[18571400] 'compare' (duration: 64.663829ms)"],"step_count":2} Sep 11 00:19:11.780925 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.780493Z","caller":"traceutil/trace.go:171","msg":"trace[1250658961] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"130.463237ms","start":"2025-09-11T00:19:11.650011Z","end":"2025-09-11T00:19:11.780474Z","steps":["trace[1250658961] 'process raft request' (duration: 130.382202ms)"],"step_count":1} Sep 11 00:19:11.905001 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:11.904264Z","caller":"traceutil/trace.go:171","msg":"trace[351483723] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"115.854085ms","start":"2025-09-11T00:19:11.788386Z","end":"2025-09-11T00:19:11.904240Z","steps":["trace[351483723] 'process raft request' (duration: 85.492644ms)","trace[351483723] 'compare' (duration: 30.164804ms)"],"step_count":2} Sep 11 00:19:12.305363 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:12.304780Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.124803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} Sep 11 00:19:12.305363 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.304937Z","caller":"traceutil/trace.go:171","msg":"trace[450956626] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"300.101926ms","start":"2025-09-11T00:19:12.004823Z","end":"2025-09-11T00:19:12.304925Z","steps":["trace[450956626] 'process raft request' (duration: 300.040438ms)"],"step_count":1} Sep 11 00:19:12.305363 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.304945Z","caller":"traceutil/trace.go:171","msg":"trace[1811861391] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"301.593154ms","start":"2025-09-11T00:19:12.003339Z","end":"2025-09-11T00:19:12.304932Z","steps":["trace[1811861391] 'compare' (duration: 299.000304ms)"],"step_count":1} Sep 11 00:19:12.305363 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:12.305034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:19:12.003312Z","time spent":"301.695661ms","remote":"10.0.0.74:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2414,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} Sep 11 00:19:12.305890 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:12.305032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:19:12.004796Z","time spent":"300.188591ms","remote":"10.0.0.74:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:19:12.341178 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.340735Z","caller":"traceutil/trace.go:171","msg":"trace[1527977820] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"326.160152ms","start":"2025-09-11T00:19:12.014555Z","end":"2025-09-11T00:19:12.340715Z","steps":["trace[1527977820] 'process raft request' (duration: 325.915562ms)"],"step_count":1} Sep 11 00:19:12.341178 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:12.340873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:19:12.014494Z","time spent":"326.294139ms","remote":"10.0.0.74:46206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:19:12.519449 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.519025Z","caller":"traceutil/trace.go:171","msg":"trace[1125321169] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"209.30904ms","start":"2025-09-11T00:19:12.309699Z","end":"2025-09-11T00:19:12.519008Z","steps":["trace[1125321169] 'process raft request' (duration: 209.249746ms)"],"step_count":1} Sep 11 00:19:12.519449 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.519055Z","caller":"traceutil/trace.go:171","msg":"trace[1742789192] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"210.155873ms","start":"2025-09-11T00:19:12.308886Z","end":"2025-09-11T00:19:12.519042Z","steps":["trace[1742789192] 'process raft request' (duration: 197.724893ms)","trace[1742789192] 'compare' (duration: 12.235756ms)"],"step_count":2} Sep 11 00:19:12.552735 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.552185Z","caller":"traceutil/trace.go:171","msg":"trace[302032937] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"206.934478ms","start":"2025-09-11T00:19:12.345229Z","end":"2025-09-11T00:19:12.552163Z","steps":["trace[302032937] 'process raft request' (duration: 206.778468ms)"],"step_count":1} Sep 11 00:19:12.574626 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.572501Z","caller":"traceutil/trace.go:171","msg":"trace[1100913429] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:455; }","duration":"211.332839ms","start":"2025-09-11T00:19:12.361142Z","end":"2025-09-11T00:19:12.572474Z","steps":["trace[1100913429] 'read index received' (duration: 145.48217ms)","trace[1100913429] 'applied index is now lower than readState.Index' (duration: 65.847443ms)"],"step_count":2} Sep 11 00:19:12.574626 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:12.572907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.746001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} Sep 11 00:19:12.574626 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:12.573012Z","caller":"traceutil/trace.go:171","msg":"trace[1431153189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:445; }","duration":"211.870881ms","start":"2025-09-11T00:19:12.361098Z","end":"2025-09-11T00:19:12.572968Z","steps":["trace[1431153189] 'agreement among raft nodes before linearized reading' (duration: 211.59868ms)"],"step_count":1} Sep 11 00:19:13.131043 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:13.130619Z","caller":"traceutil/trace.go:171","msg":"trace[90688157] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"122.358544ms","start":"2025-09-11T00:19:13.008236Z","end":"2025-09-11T00:19:13.130594Z","steps":["trace[90688157] 'process raft request' (duration: 89.58482ms)","trace[90688157] 'compare' (duration: 32.61551ms)"],"step_count":2} Sep 11 00:19:13.136233 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:13.135893Z","caller":"traceutil/trace.go:171","msg":"trace[1891691137] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"110.621987ms","start":"2025-09-11T00:19:13.025243Z","end":"2025-09-11T00:19:13.135865Z","steps":["trace[1891691137] 'process raft request' (duration: 110.413056ms)"],"step_count":1} Sep 11 00:19:13.136233 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:13.136071Z","caller":"traceutil/trace.go:171","msg":"trace[1204200013] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"108.883867ms","start":"2025-09-11T00:19:13.027173Z","end":"2025-09-11T00:19:13.136057Z","steps":["trace[1204200013] 'process raft request' (duration: 108.6507ms)"],"step_count":1} Sep 11 00:19:20.134998 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134259Z","caller":"traceutil/trace.go:171","msg":"trace[2016619182] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"124.919457ms","start":"2025-09-11T00:19:20.009312Z","end":"2025-09-11T00:19:20.134232Z","steps":["trace[2016619182] 'process raft request' (duration: 124.884872ms)"],"step_count":1} Sep 11 00:19:20.135681 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134387Z","caller":"traceutil/trace.go:171","msg":"trace[1387879854] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"129.694473ms","start":"2025-09-11T00:19:20.004684Z","end":"2025-09-11T00:19:20.134378Z","steps":["trace[1387879854] 'process raft request' (duration: 129.458622ms)"],"step_count":1} Sep 11 00:19:20.135681 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134425Z","caller":"traceutil/trace.go:171","msg":"trace[698157574] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:697; }","duration":"132.619247ms","start":"2025-09-11T00:19:20.001797Z","end":"2025-09-11T00:19:20.134417Z","steps":["trace[698157574] 'read index received' (duration: 4.515239ms)","trace[698157574] 'applied index is now lower than readState.Index' (duration: 128.103357ms)"],"step_count":2} Sep 11 00:19:20.135681 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:20.134595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.781457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/calico-system/whisker\" limit:1 ","response":"range_response_count:1 size:765"} Sep 11 00:19:20.135681 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134636Z","caller":"traceutil/trace.go:171","msg":"trace[1553122090] range","detail":"{range_begin:/registry/services/specs/calico-system/whisker; range_end:; response_count:1; response_revision:681; }","duration":"132.866238ms","start":"2025-09-11T00:19:20.001761Z","end":"2025-09-11T00:19:20.134627Z","steps":["trace[1553122090] 'agreement among raft nodes before linearized reading' (duration: 132.727814ms)"],"step_count":1} Sep 11 00:19:20.135681 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:20.134695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.104185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/tigera-auth-reader\" limit:1 ","response":"range_response_count:0 size:5"} Sep 11 00:19:20.135812 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134726Z","caller":"traceutil/trace.go:171","msg":"trace[1930901892] range","detail":"{range_begin:/registry/rolebindings/kube-system/tigera-auth-reader; range_end:; response_count:0; response_revision:681; }","duration":"132.157957ms","start":"2025-09-11T00:19:20.002559Z","end":"2025-09-11T00:19:20.134717Z","steps":["trace[1930901892] 'agreement among raft nodes before linearized reading' (duration: 132.105386ms)"],"step_count":1} Sep 11 00:19:20.135812 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:20.134804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.099311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/calico-system/calico-cni-plugin\" limit:1 ","response":"range_response_count:1 size:537"} Sep 11 00:19:20.135812 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134822Z","caller":"traceutil/trace.go:171","msg":"trace[525718701] range","detail":"{range_begin:/registry/serviceaccounts/calico-system/calico-cni-plugin; range_end:; response_count:1; response_revision:681; }","duration":"126.145178ms","start":"2025-09-11T00:19:20.008671Z","end":"2025-09-11T00:19:20.134816Z","steps":["trace[525718701] 'agreement among raft nodes before linearized reading' (duration: 126.092047ms)"],"step_count":1} Sep 11 00:19:20.135812 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134837Z","caller":"traceutil/trace.go:171","msg":"trace[1763158785] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"133.066882ms","start":"2025-09-11T00:19:20.001764Z","end":"2025-09-11T00:19:20.134830Z","steps":["trace[1763158785] 'process raft request' (duration: 102.081038ms)","trace[1763158785] 'compare' (duration: 30.007676ms)"],"step_count":2} Sep 11 00:19:20.136003 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:20.134877Z","caller":"traceutil/trace.go:171","msg":"trace[1030185372] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"131.926264ms","start":"2025-09-11T00:19:20.002943Z","end":"2025-09-11T00:19:20.134869Z","steps":["trace[1030185372] 'process raft request' (duration: 131.030043ms)"],"step_count":1} Sep 11 00:19:44.984370 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:44.983701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.504613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} Sep 11 00:19:44.984370 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:44.983847Z","caller":"traceutil/trace.go:171","msg":"trace[2112191077] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"159.017323ms","start":"2025-09-11T00:19:44.824816Z","end":"2025-09-11T00:19:44.983833Z","steps":["trace[2112191077] 'read index received' (duration: 131.717489ms)","trace[2112191077] 'applied index is now lower than readState.Index' (duration: 27.298752ms)"],"step_count":2} Sep 11 00:19:44.984370 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:44.983981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.156535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/calico-system/calico-node-phrw7\" limit:1 ","response":"range_response_count:1 size:14760"} Sep 11 00:19:44.984370 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:44.983976Z","caller":"traceutil/trace.go:171","msg":"trace[100245968] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"300.465454ms","start":"2025-09-11T00:19:44.683486Z","end":"2025-09-11T00:19:44.983951Z","steps":["trace[100245968] 'process raft request' (duration: 96.636596ms)","trace[100245968] 'compare' (duration: 203.377513ms)"],"step_count":2} Sep 11 00:19:44.985210 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:44.984004Z","caller":"traceutil/trace.go:171","msg":"trace[385552896] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"197.229686ms","start":"2025-09-11T00:19:44.786740Z","end":"2025-09-11T00:19:44.983970Z","steps":["trace[385552896] 'process raft request' (duration: 197.051379ms)"],"step_count":1} Sep 11 00:19:44.985210 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:44.984134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:19:44.683472Z","time spent":"300.597684ms","remote":"10.0.0.74:55760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1140,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:19:44.985210 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:44.984010Z","caller":"traceutil/trace.go:171","msg":"trace[404222927] range","detail":"{range_begin:/registry/pods/calico-system/calico-node-phrw7; range_end:; response_count:1; response_revision:920; }","duration":"159.220107ms","start":"2025-09-11T00:19:44.824782Z","end":"2025-09-11T00:19:44.984002Z","steps":["trace[404222927] 'agreement among raft nodes before linearized reading' (duration: 159.094719ms)"],"step_count":1} Sep 11 00:19:47.979757 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:47.979272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.399354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-674b8bbfcf-lv4rk.1864126365657183\" limit:1 ","response":"range_response_count:1 size:824"} Sep 11 00:19:47.979757 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:47.979371Z","caller":"traceutil/trace.go:171","msg":"trace[1978519375] range","detail":"{range_begin:/registry/events/kube-system/coredns-674b8bbfcf-lv4rk.1864126365657183; range_end:; response_count:1; response_revision:1007; }","duration":"106.529451ms","start":"2025-09-11T00:19:47.872812Z","end":"2025-09-11T00:19:47.979342Z","steps":["trace[1978519375] 'range keys from in-memory index tree' (duration: 106.266122ms)"],"step_count":1} Sep 11 00:19:49.048644 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.048226Z","caller":"traceutil/trace.go:171","msg":"trace[2070509824] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"153.080825ms","start":"2025-09-11T00:19:48.895129Z","end":"2025-09-11T00:19:49.048210Z","steps":["trace[2070509824] 'process raft request' (duration: 152.928787ms)"],"step_count":1} Sep 11 00:19:49.048644 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.048206Z","caller":"traceutil/trace.go:171","msg":"trace[652294090] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1069; }","duration":"151.022197ms","start":"2025-09-11T00:19:48.897151Z","end":"2025-09-11T00:19:49.048173Z","steps":["trace[652294090] 'read index received' (duration: 75.480093ms)","trace[652294090] 'applied index is now lower than readState.Index' (duration: 75.541353ms)"],"step_count":2} Sep 11 00:19:49.048644 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:49.048399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.228177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/tigera-operator/calico-apiserver-certs\" limit:1 ","response":"range_response_count:1 size:4751"} Sep 11 00:19:49.048644 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.048437Z","caller":"traceutil/trace.go:171","msg":"trace[1122265135] range","detail":"{range_begin:/registry/secrets/tigera-operator/calico-apiserver-certs; range_end:; response_count:1; response_revision:1039; }","duration":"151.305744ms","start":"2025-09-11T00:19:48.897124Z","end":"2025-09-11T00:19:49.048429Z","steps":["trace[1122265135] 'agreement among raft nodes before linearized reading' (duration: 151.12945ms)"],"step_count":1} Sep 11 00:19:49.309388 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.308810Z","caller":"traceutil/trace.go:171","msg":"trace[518025346] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"219.350934ms","start":"2025-09-11T00:19:49.089430Z","end":"2025-09-11T00:19:49.308781Z","steps":["trace[518025346] 'process raft request' (duration: 168.028293ms)","trace[518025346] 'compare' (duration: 51.153371ms)"],"step_count":2} Sep 11 00:19:49.309388 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.308871Z","caller":"traceutil/trace.go:171","msg":"trace[1391781228] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1072; }","duration":"218.491136ms","start":"2025-09-11T00:19:49.090364Z","end":"2025-09-11T00:19:49.308855Z","steps":["trace[1391781228] 'read index received' (duration: 75.739307ms)","trace[1391781228] 'applied index is now lower than readState.Index' (duration: 142.750988ms)"],"step_count":2} Sep 11 00:19:49.309388 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.308938Z","caller":"traceutil/trace.go:171","msg":"trace[47385724] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"219.389517ms","start":"2025-09-11T00:19:49.089542Z","end":"2025-09-11T00:19:49.308931Z","steps":["trace[47385724] 'process raft request' (duration: 219.182946ms)"],"step_count":1} Sep 11 00:19:49.309388 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:49.309002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.619219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/calico-apiserver/calico-apiserver-certs\" limit:1 ","response":"range_response_count:1 size:4753"} Sep 11 00:19:49.309706 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.309030Z","caller":"traceutil/trace.go:171","msg":"trace[1978399296] range","detail":"{range_begin:/registry/secrets/calico-apiserver/calico-apiserver-certs; range_end:; response_count:1; response_revision:1043; }","duration":"218.679833ms","start":"2025-09-11T00:19:49.090342Z","end":"2025-09-11T00:19:49.309022Z","steps":["trace[1978399296] 'agreement among raft nodes before linearized reading' (duration: 218.555679ms)"],"step_count":1} Sep 11 00:19:49.334684 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.334261Z","caller":"traceutil/trace.go:171","msg":"trace[106378081] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"161.677144ms","start":"2025-09-11T00:19:49.172558Z","end":"2025-09-11T00:19:49.334235Z","steps":["trace[106378081] 'process raft request' (duration: 161.506921ms)"],"step_count":1} Sep 11 00:19:49.334684 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.334430Z","caller":"traceutil/trace.go:171","msg":"trace[1712946200] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"116.591103ms","start":"2025-09-11T00:19:49.217826Z","end":"2025-09-11T00:19:49.334417Z","steps":["trace[1712946200] 'process raft request' (duration: 116.365136ms)"],"step_count":1} Sep 11 00:19:49.544973 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.544632Z","caller":"traceutil/trace.go:171","msg":"trace[43766593] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"231.574616ms","start":"2025-09-11T00:19:49.313035Z","end":"2025-09-11T00:19:49.544610Z","steps":["trace[43766593] 'process raft request' (duration: 135.942654ms)","trace[43766593] 'compare' (duration: 95.463833ms)"],"step_count":2} Sep 11 00:19:49.545281 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.545100Z","caller":"traceutil/trace.go:171","msg":"trace[752421099] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"226.71008ms","start":"2025-09-11T00:19:49.318381Z","end":"2025-09-11T00:19:49.545091Z","steps":["trace[752421099] 'process raft request' (duration: 226.188032ms)"],"step_count":1} Sep 11 00:19:49.566214 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.565875Z","caller":"traceutil/trace.go:171","msg":"trace[1507282635] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1077; }","duration":"189.520523ms","start":"2025-09-11T00:19:49.376347Z","end":"2025-09-11T00:19:49.565868Z","steps":["trace[1507282635] 'read index received' (duration: 72.638428ms)","trace[1507282635] 'applied index is now lower than readState.Index' (duration: 116.881725ms)"],"step_count":2} Sep 11 00:19:49.566214 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.565913Z","caller":"traceutil/trace.go:171","msg":"trace[1609038049] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"225.972153ms","start":"2025-09-11T00:19:49.339931Z","end":"2025-09-11T00:19:49.565903Z","steps":["trace[1609038049] 'process raft request' (duration: 225.894056ms)"],"step_count":1} Sep 11 00:19:49.566214 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:49.565973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.613229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/operator.tigera.io/installations/default\" limit:1 ","response":"range_response_count:1 size:3643"} Sep 11 00:19:49.566214 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:49.566040Z","caller":"traceutil/trace.go:171","msg":"trace[386711405] range","detail":"{range_begin:/registry/operator.tigera.io/installations/default; range_end:; response_count:1; response_revision:1048; }","duration":"189.723077ms","start":"2025-09-11T00:19:49.376310Z","end":"2025-09-11T00:19:49.566033Z","steps":["trace[386711405] 'agreement among raft nodes before linearized reading' (duration: 189.588432ms)"],"step_count":1} Sep 11 00:19:51.116426 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:51.116028Z","caller":"traceutil/trace.go:171","msg":"trace[754842965] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"121.39279ms","start":"2025-09-11T00:19:50.994612Z","end":"2025-09-11T00:19:51.116005Z","steps":["trace[754842965] 'process raft request' (duration: 117.775126ms)"],"step_count":1} Sep 11 00:19:57.371650 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:57.371204Z","caller":"traceutil/trace.go:171","msg":"trace[1842353713] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"173.589757ms","start":"2025-09-11T00:19:57.197593Z","end":"2025-09-11T00:19:57.371183Z","steps":["trace[1842353713] 'read index received' (duration: 173.168351ms)","trace[1842353713] 'applied index is now lower than readState.Index' (duration: 420.675µs)"],"step_count":2} Sep 11 00:19:57.371650 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:57.371330Z","caller":"traceutil/trace.go:171","msg":"trace[1224319982] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"211.852851ms","start":"2025-09-11T00:19:57.159437Z","end":"2025-09-11T00:19:57.371290Z","steps":["trace[1224319982] 'process raft request' (duration: 211.332317ms)"],"step_count":1} Sep 11 00:19:57.372376 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:19:57.371830Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.7713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/crd.projectcalico.org/tiers/default\" limit:1 ","response":"range_response_count:1 size:702"} Sep 11 00:19:57.372376 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:19:57.371896Z","caller":"traceutil/trace.go:171","msg":"trace[1245086640] range","detail":"{range_begin:/registry/crd.projectcalico.org/tiers/default; range_end:; response_count:1; response_revision:1118; }","duration":"174.320217ms","start":"2025-09-11T00:19:57.197552Z","end":"2025-09-11T00:19:57.371872Z","steps":["trace[1245086640] 'agreement among raft nodes before linearized reading' (duration: 173.715655ms)"],"step_count":1} Sep 11 00:20:12.689780 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:20:12.689112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.944385ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} Sep 11 00:20:12.689780 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:12.689304Z","caller":"traceutil/trace.go:171","msg":"trace[1010372468] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"578.014472ms","start":"2025-09-11T00:20:12.111276Z","end":"2025-09-11T00:20:12.689290Z","steps":["trace[1010372468] 'process raft request' (duration: 577.930734ms)"],"step_count":1} Sep 11 00:20:12.689780 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:20:12.689388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2025-09-11T00:20:12.111252Z","time spent":"578.080066ms","remote":"10.0.0.74:46460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} Sep 11 00:20:16.956191 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:16.955786Z","caller":"traceutil/trace.go:171","msg":"trace[356468438] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"254.327316ms","start":"2025-09-11T00:20:16.701440Z","end":"2025-09-11T00:20:16.955768Z","steps":["trace[356468438] 'process raft request' (duration: 254.177124ms)"],"step_count":1} Sep 11 00:20:17.129275 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:20:17.128780Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.865807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/10.0.0.74\" limit:1 ","response":"range_response_count:1 size:125"} Sep 11 00:20:17.129275 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:17.128883Z","caller":"traceutil/trace.go:171","msg":"trace[810708017] range","detail":"{range_begin:/registry/masterleases/10.0.0.74; range_end:; response_count:1; response_revision:1260; }","duration":"104.031259ms","start":"2025-09-11T00:20:17.024836Z","end":"2025-09-11T00:20:17.128867Z","steps":["trace[810708017] 'range keys from in-memory index tree' (duration: 103.705795ms)"],"step_count":1} Sep 11 00:20:19.515863 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:19.515288Z","caller":"traceutil/trace.go:171","msg":"trace[1720494810] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"114.528724ms","start":"2025-09-11T00:20:19.400732Z","end":"2025-09-11T00:20:19.515261Z","steps":["trace[1720494810] 'process raft request' (duration: 76.045961ms)","trace[1720494810] 'compare' (duration: 38.321149ms)"],"step_count":2} Sep 11 00:20:19.515863 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:19.515285Z","caller":"traceutil/trace.go:171","msg":"trace[1776793339] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"113.375328ms","start":"2025-09-11T00:20:19.401892Z","end":"2025-09-11T00:20:19.515267Z","steps":["trace[1776793339] 'read index received' (duration: 74.897415ms)","trace[1776793339] 'applied index is now lower than readState.Index' (duration: 38.477192ms)"],"step_count":2} Sep 11 00:20:19.516759 etcd-wrapper[1830]: {"level":"warn","ts":"2025-09-11T00:20:19.515595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.600565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/calico-apiserver-access-calico-crds\" limit:1 ","response":"range_response_count:1 size:661"} Sep 11 00:20:19.516759 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:19.515697Z","caller":"traceutil/trace.go:171","msg":"trace[515214351] range","detail":"{range_begin:/registry/clusterrolebindings/calico-apiserver-access-calico-crds; range_end:; response_count:1; response_revision:1275; }","duration":"113.811964ms","start":"2025-09-11T00:20:19.401869Z","end":"2025-09-11T00:20:19.515681Z","steps":["trace[515214351] 'agreement among raft nodes before linearized reading' (duration: 113.445573ms)"],"step_count":1} Sep 11 00:20:35.809284 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:35.808588Z","caller":"traceutil/trace.go:171","msg":"trace[1446592176] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"119.504144ms","start":"2025-09-11T00:20:35.689048Z","end":"2025-09-11T00:20:35.808552Z","steps":["trace[1446592176] 'process raft request' (duration: 119.316131ms)"],"step_count":1} Sep 11 00:20:35.845599 etcd-wrapper[1830]: {"level":"info","ts":"2025-09-11T00:20:35.845173Z","caller":"traceutil/trace.go:171","msg":"trace[800206293] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"128.954858ms","start":"2025-09-11T00:20:35.716193Z","end":"2025-09-11T00:20:35.845148Z","steps":["trace[800206293] 'process raft request' (duration: 113.864369ms)","trace[800206293] 'compare' (duration: 14.977907ms)"],"step_count":2}